Hello,
I am trying to setup a cloudstack managed xenserver. I had a fresh
xenserver 6.2 install, and I was able to upload my ISO. Whenever I try to
create an instance, I get the error that I have pasted below in this email.
In my setup, the cloudstack-management box is hosting an NFS share for the
secondary storage, and when I set up the zone, I made it use local storage
on the xenserver host. That drive is 100% free.
What is going wrong here? Thanks
2014-01-23 13:50:22,737 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-2:null) SeqA 3-39: Processing Seq 3-39: { Cmd ,
MgmtId: -1, via: 3, Ver: v1, Flags: 11,
[{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n
\"connections\": []\n}","wait":0}}] }
2014-01-23 13:50:22,741 DEBUG [agent.manager.AgentManagerImpl]
(AgentManager-Handler-2:null) SeqA 3-39: Sending Seq 3-39: { Ans: ,
MgmtId: 218269705676436, via: 3, Ver: v1, Flags: 100010,
[{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2014-01-23 13:50:22,891 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null)
===START=== 10.20.0.18 -- GET
command=deployVirtualMachine&zoneId=68a397f1-b141-4818-8999-7eb9d2cf39fc&templateId=ad6163bf-b4b2-4d68-a9eb-fece57c975ce&hypervisor=XenServer&serviceOfferingId=0e564524-751a-41a3-aed6-dc2344045e2d&diskOfferingId=636ab890-4f91-43b3-a50d-0af1e6e40352&networkIds=0e1c8d43-7714-4de1-99d5-8344a33affb7&response=json&sessionkey=MZcKlC%2BbG5N5o1F9aUej178nY%2F0%3D&_=1390503666841
2014-01-23 13:50:22,899 DEBUG [cloud.api.ApiDispatcher]
(catalina-exec-3:null) InfrastructureEntity name
is:com.cloud.offering.ServiceOffering
2014-01-23 13:50:22,903 DEBUG [cloud.api.ApiDispatcher]
(catalina-exec-3:null) ControlledEntity name
is:com.cloud.template.VirtualMachineTemplate
2014-01-23 13:50:22,907 DEBUG [cloud.api.ApiDispatcher]
(catalina-exec-3:null) ControlledEntity name is:com.cloud.network.Network
2014-01-23 13:50:22,911 DEBUG [cloud.api.ApiDispatcher]
(catalina-exec-3:null) InfrastructureEntity name
is:com.cloud.offering.DiskOffering
2014-01-23 13:50:22,926 DEBUG [cloud.network.NetworkModelImpl]
(catalina-exec-3:null) Service SecurityGroup is not supported in the
network id=204
2014-01-23 13:50:22,938 DEBUG [cloud.vm.UserVmManagerImpl]
(catalina-exec-3:null) Allocating in the DB for vm
2014-01-23 13:50:22,950 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(catalina-exec-3:null) Allocating entries for VM:
VM[User|78138d6d-d15a-42c9-b406-a229c04d065d]
2014-01-23 13:50:22,951 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(catalina-exec-3:null) Allocating nics for
VM[User|78138d6d-d15a-42c9-b406-a229c04d065d]
2014-01-23 13:50:22,952 DEBUG [cloud.network.NetworkManagerImpl]
(catalina-exec-3:null) Allocating nic for vm
VM[User|78138d6d-d15a-42c9-b406-a229c04d065d] in network Ntwk[204|Guest|8]
with requested profile NicProfile[0-0-null-null-null
2014-01-23 13:50:22,968 DEBUG [cloud.network.NetworkModelImpl]
(catalina-exec-3:null) Service SecurityGroup is not supported in the
network id=204
2014-01-23 13:50:22,970 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(catalina-exec-3:null) Allocating disks for
VM[User|78138d6d-d15a-42c9-b406-a229c04d065d]
2014-01-23 13:50:22,981 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(catalina-exec-3:null) Allocation completed for VM:
VM[User|78138d6d-d15a-42c9-b406-a229c04d065d]
2014-01-23 13:50:22,981 DEBUG [cloud.vm.UserVmManagerImpl]
(catalina-exec-3:null) Successfully allocated DB entry for
VM[User|78138d6d-d15a-42c9-b406-a229c04d065d]
2014-01-23 13:50:23,009 DEBUG [cloud.network.NetworkModelImpl]
(catalina-exec-3:null) Service SecurityGroup is not supported in the
network id=204
2014-01-23 13:50:23,014 DEBUG [cloud.network.NetworkModelImpl]
(catalina-exec-3:null) Service SecurityGroup is not supported in the
network id=204
2014-01-23 13:50:23,030 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-3:null) submit async job-43 = [
ab3b4b08-3667-4044-b061-ae7721482e94 ], details: AsyncJobVO {id:43, userId:
2, accountId: 2, sessionKey: null, instanceType: VirtualMachine,
instanceId: 11, cmd: org.apache.cloudstack.api.command.user.vm.DeployVMCmd,
cmdOriginator: null, cmdInfo:
{"sessionkey":"MZcKlC+bG5N5o1F9aUej178nY/0\u003d","cmdEventType":"VM.CREATE","ctxUserId":"2","serviceOfferingId":"0e564524-751a-41a3-aed6-dc2344045e2d","httpmethod":"GET","zoneId":"68a397f1-b141-4818-8999-7eb9d2cf39fc","templateId":"ad6163bf-b4b2-4d68-a9eb-fece57c975ce","response":"json","id":"11","networkIds":"0e1c8d43-7714-4de1-99d5-8344a33affb7","hypervisor":"XenServer","diskOfferingId":"636ab890-4f91-43b3-a50d-0af1e6e40352","_":"1390503666841","ctxAccountId":"2","ctxStartEventId":"137"},
cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0,
processStatus: 0, resultCode: 0, result: null, initMsid: 218269705676436,
completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2014-01-23 13:50:23,032 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
Executing org.apache.cloudstack.api.command.user.vm.DeployVMCmd for job-43
= [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
2014-01-23 13:50:23,034 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null)
===END=== 10.20.0.18 -- GET
command=deployVirtualMachine&zoneId=68a397f1-b141-4818-8999-7eb9d2cf39fc&templateId=ad6163bf-b4b2-4d68-a9eb-fece57c975ce&hypervisor=XenServer&serviceOfferingId=0e564524-751a-41a3-aed6-dc2344045e2d&diskOfferingId=636ab890-4f91-43b3-a50d-0af1e6e40352&networkIds=0e1c8d43-7714-4de1-99d5-8344a33affb7&response=json&sessionkey=MZcKlC%2BbG5N5o1F9aUej178nY%2F0%3D&_=1390503666841
2014-01-23 13:50:23,038 DEBUG [cloud.api.ApiDispatcher]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
InfrastructureEntity name is:com.cloud.offering.ServiceOffering
2014-01-23 13:50:23,040 DEBUG [cloud.api.ApiDispatcher]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
ControlledEntity name is:com.cloud.template.VirtualMachineTemplate
2014-01-23 13:50:23,044 DEBUG [cloud.api.ApiDispatcher]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
ControlledEntity name is:com.cloud.network.Network
2014-01-23 13:50:23,047 DEBUG [cloud.api.ApiDispatcher]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
InfrastructureEntity name is:com.cloud.offering.DiskOffering
2014-01-23 13:50:23,076 DEBUG [cloud.network.NetworkModelImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Service
SecurityGroup is not supported in the network id=204
2014-01-23 13:50:23,081 DEBUG [cloud.network.NetworkModelImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Service
SecurityGroup is not supported in the network id=204
2014-01-23 13:50:23,102 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Deploy
avoids pods: [], clusters: [], hosts: []
2014-01-23 13:50:23,105 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
DeploymentPlanner allocation algorithm:
com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_913f7ef8@275369c0
2014-01-23 13:50:23,105 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Trying
to allocate a host and storage pools from dc:1, pod:null,cluster:null,
requested cpu: 1000, requested ram: 1073741824
2014-01-23 13:50:23,105 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Is ROOT
volume READY (pool already allocated)?: No
2014-01-23 13:50:23,106 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
Searching all possible resources under this Zone: 1
2014-01-23 13:50:23,107 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Listing
clusters in order of aggregate capacity, that have (atleast one host with)
enough CPU and RAM capacity under this Zone: 1
2014-01-23 13:50:23,111 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
Removing from the clusterId list these clusters from avoid set: []
2014-01-23 13:50:23,119 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
Checking resources in Cluster: 1 under Pod: 1
2014-01-23 13:50:23,121 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1
2014-01-23 13:50:23,126 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for
allocation: [Host[-2-Routing]]
2014-01-23 13:50:23,129 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Found 1 hosts for allocation after
prioritization: [Host[-2-Routing]]
2014-01-23 13:50:23,129 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Looking for speed=1000Mhz, Ram=1024
2014-01-23 13:50:23,135 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Checking if host: 2 has enough capacity for
requested CPU: 1000 and requested RAM: 1073741824 ,
cpuOverprovisioningFactor: 1.0
2014-01-23 13:50:23,138 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Hosts's actual total CPU: 42656 and CPU after
applying overprovisioning: 42656
2014-01-23 13:50:23,138 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Free CPU: 41656 , Requested CPU: 1000
2014-01-23 13:50:23,138 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Free RAM: 146498420736 , Requested RAM: 1073741824
2014-01-23 13:50:23,138 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Host has enough CPU and RAM available
2014-01-23 13:50:23,138 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 2, used: 1000,
reserved: 0, actual total: 42656, total with overprovisioning: 42656;
requested cpu:1000,alloc_from_last_host?:false ,considerReservedCapacity?:
true
2014-01-23 13:50:23,138 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 2, used:
1342177280, reserved: 0, total: 147840598016; requested mem:
1073741824,alloc_from_last_host?:false ,considerReservedCapacity?: true
2014-01-23 13:50:23,139 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Found a suitable host, adding to list: 2
2014-01-23 13:50:23,139 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]
FirstFitRoutingAllocator) Host Allocator returning 1 suitable hosts
2014-01-23 13:50:23,140 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
Checking suitable pools for volume (Id, Type): (11,ROOT)
2014-01-23 13:50:23,141 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) We need
to allocate new storagepool for this volume
2014-01-23 13:50:23,142 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Calling
StoragePoolAllocators to find suitable pools
2014-01-23 13:50:23,142 DEBUG [storage.allocator.LocalStoragePoolAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
LocalStoragePoolAllocator trying to find storage pool to fit the vm
2014-01-23 13:50:23,142 DEBUG
[storage.allocator.ClusterScopeStoragePoolAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
ClusterScopeStoragePoolAllocator looking for storage pool
2014-01-23 13:50:23,143 DEBUG
[storage.allocator.ClusterScopeStoragePoolAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Looking
for pools in dc: 1 pod:1 cluster:1
2014-01-23 13:50:23,145 DEBUG
[storage.allocator.ClusterScopeStoragePoolAllocator]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) No
storage pools available for shared volume allocation, returning
2014-01-23 13:50:23,145 DEBUG
[storage.allocator.ZoneWideStoragePoolAllocator] (Job-Executor-12:job-43 =
[ ab3b4b08-3667-4044-b061-ae7721482e94 ]) ZoneWideStoragePoolAllocator to
find storage pool
2014-01-23 13:50:23,150 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) No
suitable pools found for volume: Vol[11|vm=11|ROOT] under cluster: 1
2014-01-23 13:50:23,150 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) No
suitable pools found
2014-01-23 13:50:23,150 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) No
suitable storagePools found under this Cluster: 1
2014-01-23 13:50:23,153 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Could
not find suitable Deployment Destination for this VM under any clusters,
returning.
2014-01-23 13:50:23,153 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
Searching all possible resources under this Zone: 1
2014-01-23 13:50:23,154 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Listing
clusters in order of aggregate capacity, that have (atleast one host with)
enough CPU and RAM capacity under this Zone: 1
2014-01-23 13:50:23,158 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
Removing from the clusterId list these clusters from avoid set: [1]
2014-01-23 13:50:23,161 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) No
clusters found after removing disabled clusters and clusters in avoid list,
returning.
2014-01-23 13:50:23,164 DEBUG [cloud.vm.UserVmManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
Destroying vm VM[User|78138d6d-d15a-42c9-b406-a229c04d065d] as it failed to
create on Host with Id:null
2014-01-23 13:50:23,174 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) VM
state transitted from :Stopped to Error with event:
OperationFailedToErrorvm's original host id: null new host id: null host id
before state transition: null
2014-01-23 13:50:23,183 WARN [apache.cloudstack.alerts]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
alertType:: 8 // dataCenterId:: 1 // podId:: null // clusterId:: null //
message:: Failed to deploy Vm with Id: 11, on Host with Id: null
2014-01-23 13:50:23,210 INFO [user.vm.DeployVMCmd] (Job-Executor-12:job-43
= [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM[User|78138d6d-d15a-42c9-b406-a229c04d065d]Scope=interface
com.cloud.dc.DataCenter; id=1
2014-01-23 13:50:23,210 INFO [user.vm.DeployVMCmd] (Job-Executor-12:job-43
= [ ab3b4b08-3667-4044-b061-ae7721482e94 ]) Unable to create a deployment
for VM[User|78138d6d-d15a-42c9-b406-a229c04d065d]
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM[User|78138d6d-d15a-42c9-b406-a229c04d065d]Scope=interface
com.cloud.dc.DataCenter; id=1
at
org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.reserveVirtualMachine(VMEntityManagerImpl.java:209)
at
org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.reserve(VirtualMachineEntityImpl.java:198)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3405)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2966)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2952)
at
com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at
org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:420)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
at
com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:701)
2014-01-23 13:50:23,212 DEBUG [cloud.async.AsyncJobManagerImpl]
(Job-Executor-12:job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ])
Complete async job-43 = [ ab3b4b08-3667-4044-b061-ae7721482e94 ],
jobStatus: 2, resultCode: 530, result: Error Code: 533 Error text: Unable
to create a deployment for VM[User|78138d6d-d15a-42c9-b406-a229c04d065d]
2014-01-23 13:50:26,154 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null)
===START=== 10.20.0.18 -- GET
command=queryAsyncJobResult&jobId=ab3b4b08-3667-4044-b061-ae7721482e94&response=json&sessionkey=MZcKlC%2BbG5N5o1F9aUej178nY%2F0%3D&_=1390503670174
2014-01-23 13:50:26,168 DEBUG [cloud.async.AsyncJobManagerImpl]
(catalina-exec-2:null) Async job-43 = [
ab3b4b08-3667-4044-b061-ae7721482e94 ] completed
2014-01-23 13:50:26,173 DEBUG [cloud.api.ApiServlet] (catalina-exec-2:null)
===END=== 10.20.0.18 -- GET
command=queryAsyncJobResult&jobId=ab3b4b08-3667-4044-b061-ae7721482e94&response=json&sessionkey=MZcKlC%2BbG5N5o1F9aUej178nY%2F0%3D&_=1390503670174
2014-01-23 13:50:26,190 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null)
===START=== 10.20.0.18 -- GET
command=listVirtualMachines&id=78138d6d-d15a-42c9-b406-a229c04d065d&response=json&sessionkey=MZcKlC%2BbG5N5o1F9aUej178nY%2F0%3D&_=1390503670204
