You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users@cloudstack.apache.org by Jeroen Keerl <je...@keerl-it.com> on 2016/09/18 13:07:36 UTC
ACS 4.9 Basic Networking on XEN 6.5 - Cannot create instances anymore
Hi,
I can't create new instances anymore on my ACS 4.9 on XEN, with basic
networking.
Last Thursday, this was possible without any issues.
Since then, I have tested 2 things:
- Creating domains and accounts
- Adding SSH auth to one VM I had created with an ISO
(So, no real issues, I'd assume)
However, after I destroyed the one VM mentioned above, I tried to set up a
new one, so I could create a base template.
But alas: All methods I tried fail (API and Web)
To me it looks, like it is looking "in the wrong direction", as it can't
seem to see my cluster, hosts etc.
However, looking under "Infrastructure" - all seems to be fine.
First "solution": Reboot mgmt server and system vms: No success.
2016-09-18 14:55:37,115 DEBUG [c.c.a.ApiServlet]
(catalina-exec-3:ctx-a5945e12) (logid:3dd43961) ===START=== 192.168.254.142
-- GET
command=deployVirtualMachine&response=json&zoneid=baee96f3-fee4-4e18-9f44-42
8206d1b3f3&templateid=7662eb72-791c-11e6-9aa7-00155dfa0a0e&hypervisor=XenSer
ver&serviceofferingid=083d3f86-4b95-44c8-8361-0e571890ae2a&securitygroupids=
07265180-791d-11e6-9aa7-00155dfa0a0e&_=1474203217288
2016-09-18 14:55:37,132 DEBUG [c.c.u.AccountManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Access granted
to Acct[07261698-791d-11e6-9aa7-00155dfa0a0e-admin] to
org.apache.cloudstack.quota.vo.ServiceOfferingVO$$EnhancerByCGLIB$$7f648b5a@
5724d0d6 by AffinityGroupAccessChecker
2016-09-18 14:55:37,133 DEBUG [c.c.u.AccountManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Access granted
to Acct[07261698-791d-11e6-9aa7-00155dfa0a0e-admin] to null by
AffinityGroupAccessChecker
2016-09-18 14:55:37,158 DEBUG [c.c.v.UserVmManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating in
the DB for vm
2016-09-18 14:55:37,165 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating
entries for VM: VM[User|i-2-15-VM]
2016-09-18 14:55:37,165 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating nics
for VM[User|i-2-15-VM]
2016-09-18 14:55:37,166 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating nic
for vm VM[User|i-2-15-VM] in network Ntwk[204|Guest|6] with requested
profile NicProfile[0-0-null-null-null
2016-09-18 14:55:37,171 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating
disks for VM[User|i-2-15-VM]
2016-09-18 14:55:37,180 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocation
completed for VM: VM[User|i-2-15-VM]
2016-09-18 14:55:37,180 DEBUG [c.c.v.UserVmManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Successfully
allocated DB entry for VM[User|i-2-15-VM]
2016-09-18 14:55:37,221 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:1f41919b) Add job-135 into
job monitoring
2016-09-18 14:55:37,231 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) submit async
job-135, details: AsyncJobVO {id:135, userId: 2, accountId: 2, instanceType:
VirtualMachine, instanceId: 15, cmd:
org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin, cmdInfo:
{"id":"15","response":"json","serviceofferingid":"083d3f86-4b95-44c8-8361-0e
571890ae2a","ctxDetails":"{\"interface
com.cloud.vm.VirtualMachine\":\"d483480a-fa2d-47d2-ab9d-4355015f829e\",\"int
erface
com.cloud.template.VirtualMachineTemplate\":\"7662eb72-791c-11e6-9aa7-00155d
fa0a0e\",\"interface
com.cloud.network.security.SecurityGroup\":\"07265180-791d-11e6-9aa7-00155df
a0a0e\",\"interface
com.cloud.offering.ServiceOffering\":\"083d3f86-4b95-44c8-8361-0e571890ae2a\
",\"interface
com.cloud.dc.DataCenter\":\"baee96f3-fee4-4e18-9f44-428206d1b3f3\"}","cmdEve
ntType":"VM.CREATE","ctxUserId":"2","zoneid":"baee96f3-fee4-4e18-9f44-428206
d1b3f3","hypervisor":"XenServer","templateid":"7662eb72-791c-11e6-9aa7-00155
dfa0a0e","httpmethod":"GET","_":"1474203217288","securitygroupids":"07265180
-791d-11e6-9aa7-00155dfa0a0e","uuid":"d483480a-fa2d-47d2-ab9d-4355015f829e",
"ctxAccountId":"2","ctxStartEventId":"279"}, cmdVersion: 0, status:
IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid:
91770980878, completeMsid: null, lastUpdated: null, lastPolled: null,
created: null}
2016-09-18 14:55:37,232 DEBUG [c.c.a.ApiServlet]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) ===END===
192.168.254.142 -- GET
command=deployVirtualMachine&response=json&zoneid=baee96f3-fee4-4e18-9f44-42
8206d1b3f3&templateid=7662eb72-791c-11e6-9aa7-00155dfa0a0e&hypervisor=XenSer
ver&serviceofferingid=083d3f86-4b95-44c8-8361-0e571890ae2a&securitygroupids=
07265180-791d-11e6-9aa7-00155dfa0a0e&_=1474203217288
2016-09-18 14:55:37,236 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Executing
AsyncJobVO {id:135, userId: 2, accountId: 2, instanceType: VirtualMachine,
instanceId: 15, cmd:
org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin, cmdInfo:
{"id":"15","response":"json","serviceofferingid":"083d3f86-4b95-44c8-8361-0e
571890ae2a","ctxDetails":"{\"interface
com.cloud.vm.VirtualMachine\":\"d483480a-fa2d-47d2-ab9d-4355015f829e\",\"int
erface
com.cloud.template.VirtualMachineTemplate\":\"7662eb72-791c-11e6-9aa7-00155d
fa0a0e\",\"interface
com.cloud.network.security.SecurityGroup\":\"07265180-791d-11e6-9aa7-00155df
a0a0e\",\"interface
com.cloud.offering.ServiceOffering\":\"083d3f86-4b95-44c8-8361-0e571890ae2a\
",\"interface
com.cloud.dc.DataCenter\":\"baee96f3-fee4-4e18-9f44-428206d1b3f3\"}","cmdEve
ntType":"VM.CREATE","ctxUserId":"2","zoneid":"baee96f3-fee4-4e18-9f44-428206
d1b3f3","hypervisor":"XenServer","templateid":"7662eb72-791c-11e6-9aa7-00155
dfa0a0e","httpmethod":"GET","_":"1474203217288","securitygroupids":"07265180
-791d-11e6-9aa7-00155dfa0a0e","uuid":"d483480a-fa2d-47d2-ab9d-4355015f829e",
"ctxAccountId":"2","ctxStartEventId":"279"}, cmdVersion: 0, status:
IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid:
91770980878, completeMsid: null, lastUpdated: null, lastPolled: null,
created: null}
2016-09-18 14:55:37,278 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Deploy avoids pods: [], clusters: [], hosts: []
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
DeploymentPlanner allocation algorithm:
com.cloud.deploy.FirstFitPlanner@4bfa7932
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Trying to allocate a host and storage pools from dc:1,
pod:null,cluster:null, requested cpu: 1000, requested ram: 1073741824
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Is
ROOT volume READY (pool already allocated)?: No
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Searching all possible resources under this Zone: 1
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Listing clusters in order of aggregate capacity, that have (atleast one host
with) enough CPU and RAM capacity under this Zone: 1
2016-09-18 14:55:37,282 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Removing from the clusterId list these clusters from avoid set: []
2016-09-18 14:55:37,288 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Checking resources in Cluster: 1 under Pod: 1
2016-09-18 14:55:37,288 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for hosts in dc: 1 pod:1
cluster:1
2016-09-18 14:55:37,290 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) FirstFitAllocator has 0 hosts to
check for allocation: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Found 0 hosts for allocation
after prioritization: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for speed=1000Mhz,
Ram=1024
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Host Allocator returning 0
suitable hosts
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No
suitable hosts found
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No
suitable hosts found under this Cluster: 1
2016-09-18 14:55:37,294 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Could not find suitable Deployment Destination for this VM under any
clusters, returning.
2016-09-18 14:55:37,294 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Searching all possible resources under this Zone: 1
2016-09-18 14:55:37,295 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Listing clusters in order of aggregate capacity, that have (atleast one host
with) enough CPU and RAM capacity under this Zone: 1
2016-09-18 14:55:37,297 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Removing from the clusterId list these clusters from avoid set: [1]
2016-09-18 14:55:37,297 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No
clusters found after removing disabled clusters and clusters in avoid list,
returning.
2016-09-18 14:55:37,299 DEBUG [c.c.v.UserVmManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Destroying vm VM[User|i-2-15-VM] as it failed to create on Host with Id:null
2016-09-18 14:55:37,311 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) 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
2016-09-18 14:55:37,329 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Access granted to Acct[07261698-791d-11e6-9aa7-00155dfa0a0e-admin] to
Domain:1/ by AffinityGroupAccessChecker
2016-09-18 14:55:37,334 WARN [o.a.c.alerts]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
alertType:: 8 // dataCenterId:: 1 // podId:: null // clusterId:: null //
message:: Failed to deploy Vm with Id: 15, on Host with Id: null
2016-09-18 14:55:37,356 INFO [o.a.c.a.c.a.v.DeployVMCmdByAdmin]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM[User|i-2-15-VM]Scope=interface com.cloud.dc.DataCenter;
id=1
2016-09-18 14:55:37,356 INFO [o.a.c.a.c.a.v.DeployVMCmdByAdmin]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Unable to create a deployment for VM[User|i-2-15-VM]
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM[User|i-2-15-VM]Scope=interface com.cloud.dc.DataCenter;
id=1
at
org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.reserveVir
tualMachine(VMEntityManagerImpl.java:214)
at
org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.reser
ve(VirtualMachineEntityImpl.java:200)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:40
92)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:37
10)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:36
98)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57
)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl
.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopU
tils.java:317)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint
(ReflectiveMethodInvocation.java:183)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(Reflect
iveMethodInvocation.java:150)
at
org.apache.cloudstack.network.contrail.management.EventUtils$EventIntercepto
r.invoke(EventUtils.java:106)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(Reflect
iveMethodInvocation.java:161)
at
com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51
)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(Reflect
iveMethodInvocation.java:161)
at
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(Expos
eInvocationInterceptor.java:91)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(Reflect
iveMethodInvocation.java:172)
at
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopPro
xy.java:204)
at com.sun.proxy.$Proxy198.startVirtualMachine(Unknown
Source)
at
org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin.execute(Deploy
VMCmdByAdmin.java:50)
at
com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
at
com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
at
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext
(AsyncJobManagerImpl.java:554)
at
org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedCo
ntextRunnable.java:49)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(Defa
ultManagedContext.java:56)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithCon
text(DefaultManagedContext.java:103)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithCont
ext(DefaultManagedContext.java:53)
at
org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedCont
extRunnable.java:46)
at
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJob
ManagerImpl.java:502)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:11
45)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:6
15)
at java.lang.Thread.run(Thread.java:745)
2016-09-18 14:55:37,356 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Complete async
job-135, jobStatus: FAILED, resultCode: 530, result:
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"er
rorcode":533,"errortext":"Unable to create a deployment for
VM[User|i-2-15-VM]"}
2016-09-18 14:55:37,357 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Publish async
job-135 complete on message bus
2016-09-18 14:55:37,357 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Wake up jobs
related to job-135
2016-09-18 14:55:37,357 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Update db status
for job-135
2016-09-18 14:55:37,359 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Wake up jobs
joined with job-135 and disjoin all subjobs created from job- 135
2016-09-18 14:55:37,361 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Done executing
org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin for job-135
2016-09-18 14:55:37,361 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Remove job-135
from job monitoring
JeroenKeerl
Keerl IT Services GmbH
Birkenstraße 1b. 21521 Aumühle
+49 177 6320 317
www.keerl-it.com
info@keerl-it.com
Geschäftsführer. Jacobus J. Keerl
Registergericht Lubeck. HRB-Nr. 14511
Unsere Allgemeine Geschäftsbedingungen finden Siehier.
AW: ACS 4.9 Basic Networking on XEN 6.5 - Cannot create instances anymore
Posted by Jeroen Keerl <je...@keerl-it.com>.
Hi,
Both hosts are green, both having 24GB RAM available and ~ 2.7 TB on
primary, ~ 500GB on secondary.
In the mean time, my SVVM was lost as well:
Still showing in XENCenter, however, CS Mgmgt did not see it anymore and
tried to create a new one.
So I threw away the old one, to no avail.
After using google, a few topics came up.
One of it was verifying the vhdutil tool, which did not have the execute
rights. (Set it to 755, similar to other scripts in that directory)
Changed that, soon after that a new SSVM was online.
Unfortunately, I was so "clever" to throw away the false Volume, which
belonged to my Virtual Router VM.
Trying to fix that now...
Cheers
JK
-----Ursprüngliche Nachricht-----
Von: Sergey Levitskiy [mailto:Sergey.Levitskiy@autodesk.com]
Gesendet: Sonntag, 18. September 2016 15:39
An: users@cloudstack.apache.org; jeroen.keerl@keerl-it.com
Betreff: Re: ACS 4.9 Basic Networking on XEN 6.5 - Cannot create instances
anymore
It seems the allocator can't find any hosts with enough capacity. Check if
all your hosts are in green state , host tag in service offering matches
tags of your hosts and if you have enough free capacity.
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Trying to allocate a host and storage pools from dc:1,
pod:null,cluster:null, requested cpu: 1000, requested ram:
1073741824<tel:1073741824>
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Is
ROOT volume READY (pool already allocated)?: No
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Searching all possible resources under this Zone: 1
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Listing clusters in order of aggregate capacity, that have (atleast one host
with) enough CPU and RAM capacity under this Zone: 1
2016-09-18 14:55:37,282 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Removing from the clusterId list these clusters from avoid set: []
2016-09-18 14:55:37,288 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Checking resources in Cluster: 1 under Pod: 1
2016-09-18 14:55:37,288 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for hosts in dc: 1 pod:1
cluster:1
2016-09-18 14:55:37,290 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) FirstFitAllocator has 0 hosts to
check for allocation: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Found 0 hosts for allocation
after prioritization: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for speed=1000Mhz,
Ram=1024
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Host Allocator returning 0
suitable hosts
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No
suitable hosts found
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No
suitable hosts found under this Cluster: 1
2016-09-18 14:55:37,294 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Could not find suitable Deployment Destination for this VM under any
clusters, returning.
Sent from my iPhone
On Sep 18, 2016, at 6:08 AM, Jeroen Keerl
<je...@keerl-it.com>> wrote:
Hi,
I cant create new instances anymore on my ACS 4.9 on XEN, with basic
networking.
Last Thursday, this was possible without any issues.
Since then, I have tested 2 things:
- Creating domains and accounts
- Adding SSH auth to one VM I had created with an ISO
(So, no real issues, Id assume)
However, after I destroyed the one VM mentioned above, I tried to set up a
new one, so I could create a base template.
But alas: All methods I tried fail (API and Web) To me it looks, like it is
looking in the wrong direction, as it cant seem to see my cluster, hosts
etc.
However, looking under Infrastructure all seems to be fine.
First solution: Reboot mgmt server and system vms: No success.
2016-09-18 14:55:37,115 DEBUG [c.c.a.ApiServlet]
(catalina-exec-3:ctx-a5945e12) (logid:3dd43961) ===START=== 192.168.254.142
-- GET
command=deployVirtualMachine&response=json&zoneid=baee96f3-fee4-4e18-9f44-42
8206d1b3f3&templateid=7662eb72-791c-11e6-9aa7-00155dfa0a0e&hypervisor=XenSer
ver&serviceofferingid=083d3f86-4b95-44c8-8361-0e571890ae2a&securitygroupids=
07265180-791d-11e6-9aa7-00155dfa0a0e&_=1474203217288
2016-09-18 14:55:37,132 DEBUG [c.c.u.AccountManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Access granted
to Acct[07261698-791d-11e6-9aa7-00155dfa0a0e-admin] to
org.apache.cloudstack.quota.vo.ServiceOfferingVO$$EnhancerByCGLIB$$7f648b5a@
5724d0d6 by AffinityGroupAccessChecker
2016-09-18 14:55:37,133 DEBUG [c.c.u.AccountManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Access granted
to Acct[07261698-791d-11e6-9aa7-00155dfa0a0e-admin] to null by
AffinityGroupAccessChecker
2016-09-18 14:55:37,158 DEBUG [c.c.v.UserVmManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating in
the DB for vm
2016-09-18 14:55:37,165 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating
entries for VM: VM[User|i-2-15-VM]
2016-09-18 14:55:37,165 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating nics
for VM[User|i-2-15-VM]
2016-09-18 14:55:37,166 DEBUG [o.a.c.e.o.NetworkOrchestrator]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating nic
for vm VM[User|i-2-15-VM] in network Ntwk[204|Guest|6] with requested
profile NicProfile[0-0-null-null-null
2016-09-18 14:55:37,171 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating
disks for VM[User|i-2-15-VM]
2016-09-18 14:55:37,180 DEBUG [c.c.v.VirtualMachineManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocation
completed for VM: VM[User|i-2-15-VM]
2016-09-18 14:55:37,180 DEBUG [c.c.v.UserVmManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Successfully
allocated DB entry for VM[User|i-2-15-VM]
2016-09-18 14:55:37,221 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:1f41919b) Add job-135 into
job monitoring
2016-09-18 14:55:37,231 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) submit async
job-135, details: AsyncJobVO {id:135, userId: 2, accountId: 2, instanceType:
VirtualMachine, instanceId: 15, cmd:
org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin, cmdInfo:
{"id":"15","response":"json","serviceofferingid":"083d3f86-4b95-44c8-8361-0e
571890ae2a","ctxDetails":"{\"interface
com.cloud.vm.VirtualMachine\":\"d483480a-fa2d-47d2-ab9d-4355015f829e\",\"int
erface
com.cloud.template.VirtualMachineTemplate\":\"7662eb72-791c-11e6-9aa7-00155d
fa0a0e\",\"interface
com.cloud.network.security.SecurityGroup\":\"07265180-791d-11e6-9aa7-00155df
a0a0e\",\"interface
com.cloud.offering.ServiceOffering\":\"083d3f86-4b95-44c8-8361-0e571890ae2a\
",\"interface
com.cloud.dc.DataCenter\":\"baee96f3-fee4-4e18-9f44-428206d1b3f3\"}","cmdEve
ntType":"VM.CREATE","ctxUserId":"2","zoneid":"baee96f3-fee4-4e18-9f44-428206
d1b3f3","hypervisor":"XenServer","templateid":"7662eb72-791c-11e6-9aa7-00155
dfa0a0e","httpmethod":"GET","_":"1474203217288","securitygroupids":"07265180
-791d-11e6-9aa7-00155dfa0a0e","uuid":"d483480a-fa2d-47d2-ab9d-4355015f829e",
"ctxAccountId":"2","ctxStartEventId":"279"}, cmdVersion: 0, status:
IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid:
91770980878, completeMsid: null, lastUpdated: null, lastPolled: null,
created: null}
2016-09-18 14:55:37,232 DEBUG [c.c.a.ApiServlet]
(catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) ===END===
192.168.254.142 -- GET
command=deployVirtualMachine&response=json&zoneid=baee96f3-fee4-4e18-9f44-42
8206d1b3f3&templateid=7662eb72-791c-11e6-9aa7-00155dfa0a0e&hypervisor=XenSer
ver&serviceofferingid=083d3f86-4b95-44c8-8361-0e571890ae2a&securitygroupids=
07265180-791d-11e6-9aa7-00155dfa0a0e&_=1474203217288
2016-09-18 14:55:37,236 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Executing
AsyncJobVO {id:135, userId: 2, accountId: 2, instanceType: VirtualMachine,
instanceId: 15, cmd:
org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin, cmdInfo:
{"id":"15","response":"json","serviceofferingid":"083d3f86-4b95-44c8-8361-0e
571890ae2a","ctxDetails":"{\"interface
com.cloud.vm.VirtualMachine\":\"d483480a-fa2d-47d2-ab9d-4355015f829e\",\"int
erface
com.cloud.template.VirtualMachineTemplate\":\"7662eb72-791c-11e6-9aa7-00155d
fa0a0e\",\"interface
com.cloud.network.security.SecurityGroup\":\"07265180-791d-11e6-9aa7-00155df
a0a0e\",\"interface
com.cloud.offering.ServiceOffering\":\"083d3f86-4b95-44c8-8361-0e571890ae2a\
",\"interface
com.cloud.dc.DataCenter\":\"baee96f3-fee4-4e18-9f44-428206d1b3f3\"}","cmdEve
ntType":"VM.CREATE","ctxUserId":"2","zoneid":"baee96f3-fee4-4e18-9f44-428206
d1b3f3","hypervisor":"XenServer","templateid":"7662eb72-791c-11e6-9aa7-00155
dfa0a0e","httpmethod":"GET","_":"1474203217288","securitygroupids":"07265180
-791d-11e6-9aa7-00155dfa0a0e","uuid":"d483480a-fa2d-47d2-ab9d-4355015f829e",
"ctxAccountId":"2","ctxStartEventId":"279"}, cmdVersion: 0, status:
IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid:
91770980878, completeMsid: null, lastUpdated: null, lastPolled: null,
created: null}
2016-09-18 14:55:37,278 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Deploy avoids pods: [], clusters: [], hosts: []
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
DeploymentPlanner allocation algorithm:
com.cloud.deploy.FirstFitPlanner@4bfa7932
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Trying to allocate a host and storage pools from dc:1,
pod:null,cluster:null, requested cpu: 1000, requested ram: 1073741824
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Is
ROOT volume READY (pool already allocated)?: No
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Searching all possible resources under this Zone: 1
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Listing clusters in order of aggregate capacity, that have (atleast one host
with) enough CPU and RAM capacity under this Zone: 1
2016-09-18 14:55:37,282 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Removing from the clusterId list these clusters from avoid set: []
2016-09-18 14:55:37,288 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Checking resources in Cluster: 1 under Pod: 1
2016-09-18 14:55:37,288 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for hosts in dc: 1 pod:1
cluster:1
2016-09-18 14:55:37,290 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) FirstFitAllocator has 0 hosts to
check for allocation: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Found 0 hosts for allocation
after prioritization: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for speed=1000Mhz,
Ram=1024
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50
FirstFitRoutingAllocator) (logid:c69c1ccd) Host Allocator returning 0
suitable hosts
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No
suitable hosts found
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No
suitable hosts found under this Cluster: 1
2016-09-18 14:55:37,294 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Could not find suitable Deployment Destination for this VM under any
clusters, returning.
2016-09-18 14:55:37,294 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Searching all possible resources under this Zone: 1
2016-09-18 14:55:37,295 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Listing clusters in order of aggregate capacity, that have (atleast one host
with) enough CPU and RAM capacity under this Zone: 1
2016-09-18 14:55:37,297 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Removing from the clusterId list these clusters from avoid set: [1]
2016-09-18 14:55:37,297 DEBUG [c.c.d.FirstFitPlanner]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No
clusters found after removing disabled clusters and clusters in avoid list,
returning.
2016-09-18 14:55:37,299 DEBUG [c.c.v.UserVmManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Destroying vm VM[User|i-2-15-VM] as it failed to create on Host with Id:null
2016-09-18 14:55:37,311 DEBUG [c.c.c.CapacityManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) 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
2016-09-18 14:55:37,329 DEBUG [c.c.u.AccountManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Access granted to Acct[07261698-791d-11e6-9aa7-00155dfa0a0e-admin] to
Domain:1/ by AffinityGroupAccessChecker
2016-09-18 14:55:37,334 WARN [o.a.c.alerts]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
alertType:: 8 // dataCenterId:: 1 // podId:: null // clusterId:: null //
message:: Failed to deploy Vm with Id: 15, on Host with Id: null
2016-09-18 14:55:37,356 INFO [o.a.c.a.c.a.v.DeployVMCmdByAdmin]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM[User|i-2-15-VM]Scope=interface com.cloud.dc.DataCenter;
id=1
2016-09-18 14:55:37,356 INFO [o.a.c.a.c.a.v.DeployVMCmdByAdmin]
(API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd)
Unable to create a deployment for VM[User|i-2-15-VM]
com.cloud.exception.InsufficientServerCapacityException: Unable to create a
deployment for VM[User|i-2-15-VM]Scope=interface com.cloud.dc.DataCenter;
id=1
at
org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.reserveVir
tualMachine(VMEntityManagerImpl.java:214)
at
org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.reser
ve(VirtualMachineEntityImpl.java:200)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:40
92)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:37
10)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:36
98)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native
Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57
)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl
.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopU
tils.java:317)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint
(ReflectiveMethodInvocation.java:183)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(Reflect
iveMethodInvocation.java:150)
at
org.apache.cloudstack.network.contrail.management.EventUtils$EventIntercepto
r.invoke(EventUtils.java:106)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(Reflect
iveMethodInvocation.java:161)
at
com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51
)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(Reflect
iveMethodInvocation.java:161)
at
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(Expos
eInvocationInterceptor.java:91)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(Reflect
iveMethodInvocation.java:172)
at
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopPro
xy.java:204)
at com.sun.proxy.$Proxy198.startVirtualMachine(Unknown
Source)
at
org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin.execute(Deploy
VMCmdByAdmin.java:50)
at
com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
at
com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
at
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext
(AsyncJobManagerImpl.java:554)
at
org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedCo
ntextRunnable.java:49)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(Defa
ultManagedContext.java:56)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithCon
text(DefaultManagedContext.java:103)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithCont
ext(DefaultManagedContext.java:53)
at
org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedCont
extRunnable.java:46)
at
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJob
ManagerImpl.java:502)
at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:11
45)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:6
15)
at java.lang.Thread.run(Thread.java:745)
2016-09-18 14:55:37,356 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Complete async
job-135, jobStatus: FAILED, resultCode: 530, result:
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"er
rorcode":533,"errortext":"Unable to create a deployment for
VM[User|i-2-15-VM]"}
2016-09-18 14:55:37,357 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Publish async
job-135 complete on message bus
2016-09-18 14:55:37,357 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Wake up jobs
related to job-135
2016-09-18 14:55:37,357 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Update db status
for job-135
2016-09-18 14:55:37,359 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Wake up jobs
joined with job-135 and disjoin all subjobs created from job- 135
2016-09-18 14:55:37,361 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Done executing
org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin for job-135
2016-09-18 14:55:37,361 INFO [o.a.c.f.j.i.AsyncJobMonitor]
(API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Remove job-135
from job monitoring
Jeroen Keerl
Keerl IT Services GmbH
Birkenstraße 1b . 21521 Aumühle
+49 177 6320 317
www.keerl-it.com<http://www.keerl-it.com/>
info@keerl-it.com<ma...@keerl-it.com>
Geschäftsführer. Jacobus J. Keerl
Registergericht Lubeck. HRB-Nr. 14511
Unsere Allgemeine Geschäftsbedingungen finden Sie
hier.<http://www.keerl-it.com/AGB.pdf>
[cid:d3544f14.06fb964e.PNG.ab6bde08]
Jeroen Keerl
Keerl IT Services GmbH
Birkenstraße 1b . 21521 Aumühle
+49 177 6320 317
www.keerl-it.com
info@keerl-it.com
Geschäftsführer. Jacobus J. Keerl
Registergericht Lubeck. HRB-Nr. 14511
Unsere Allgemeine Geschäftsbedingungen finden Sie hier.
Re: ACS 4.9 Basic Networking on XEN 6.5 - Cannot create instances
anymore
Posted by Sergey Levitskiy <Se...@autodesk.com>.
It seems the allocator can't find any hosts with enough capacity. Check if all your hosts are in green state , host tag in service offering matches tags of your hosts and if you have enough free capacity.
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Trying to allocate a host and storage pools from dc:1, pod:null,cluster:null, requested cpu: 1000, requested ram: 1073741824<tel:1073741824>
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Is ROOT volume READY (pool already allocated)?: No
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Searching all possible resources under this Zone: 1
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Zone: 1
2016-09-18 14:55:37,282 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Removing from the clusterId list these clusters from avoid set: []
2016-09-18 14:55:37,288 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Checking resources in Cluster: 1 under Pod: 1
2016-09-18 14:55:37,288 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for hosts in dc: 1 pod:1 cluster:1
2016-09-18 14:55:37,290 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) FirstFitAllocator has 0 hosts to check for allocation: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) Found 0 hosts for allocation after prioritization: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for speed=1000Mhz, Ram=1024
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) Host Allocator returning 0 suitable hosts
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No suitable hosts found
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No suitable hosts found under this Cluster: 1
2016-09-18 14:55:37,294 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Could not find suitable Deployment Destination for this VM under any clusters, returning.
Sent from my iPhone
On Sep 18, 2016, at 6:08 AM, Jeroen Keerl <je...@keerl-it.com>> wrote:
Hi,
I can’t create new instances anymore on my ACS 4.9 on XEN, with basic networking.
Last Thursday, this was possible without any issues.
Since then, I have tested 2 things:
- Creating domains and accounts
- Adding SSH auth to one VM I had created with an ISO
(So, no real issues, I’d assume)
However, after I destroyed the one VM mentioned above, I tried to set up a new one, so I could create a base template.
But alas: All methods I tried fail (API and Web)
To me it looks, like it is looking “in the wrong direction”, as it can’t seem to see my cluster, hosts etc.
However, looking under “Infrastructure” – all seems to be fine.
First “solution”: Reboot mgmt server and system vms: No success.
2016-09-18 14:55:37,115 DEBUG [c.c.a.ApiServlet] (catalina-exec-3:ctx-a5945e12) (logid:3dd43961) ===START=== 192.168.254.142 -- GET command=deployVirtualMachine&response=json&zoneid=baee96f3-fee4-4e18-9f44-428206d1b3f3&templateid=7662eb72-791c-11e6-9aa7-00155dfa0a0e&hypervisor=XenServer&serviceofferingid=083d3f86-4b95-44c8-8361-0e571890ae2a&securitygroupids=07265180-791d-11e6-9aa7-00155dfa0a0e&_=1474203217288
2016-09-18 14:55:37,132 DEBUG [c.c.u.AccountManagerImpl] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Access granted to Acct[07261698-791d-11e6-9aa7-00155dfa0a0e-admin] to org.apache.cloudstack.quota.vo.ServiceOfferingVO$$EnhancerByCGLIB$$7f648b5a@5724d0d6 by AffinityGroupAccessChecker
2016-09-18 14:55:37,133 DEBUG [c.c.u.AccountManagerImpl] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Access granted to Acct[07261698-791d-11e6-9aa7-00155dfa0a0e-admin] to null by AffinityGroupAccessChecker
2016-09-18 14:55:37,158 DEBUG [c.c.v.UserVmManagerImpl] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating in the DB for vm
2016-09-18 14:55:37,165 DEBUG [c.c.v.VirtualMachineManagerImpl] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating entries for VM: VM[User|i-2-15-VM]
2016-09-18 14:55:37,165 DEBUG [c.c.v.VirtualMachineManagerImpl] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating nics for VM[User|i-2-15-VM]
2016-09-18 14:55:37,166 DEBUG [o.a.c.e.o.NetworkOrchestrator] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating nic for vm VM[User|i-2-15-VM] in network Ntwk[204|Guest|6] with requested profile NicProfile[0-0-null-null-null
2016-09-18 14:55:37,171 DEBUG [c.c.v.VirtualMachineManagerImpl] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocating disks for VM[User|i-2-15-VM]
2016-09-18 14:55:37,180 DEBUG [c.c.v.VirtualMachineManagerImpl] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Allocation completed for VM: VM[User|i-2-15-VM]
2016-09-18 14:55:37,180 DEBUG [c.c.v.UserVmManagerImpl] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) Successfully allocated DB entry for VM[User|i-2-15-VM]
2016-09-18 14:55:37,221 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:1f41919b) Add job-135 into job monitoring
2016-09-18 14:55:37,231 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) submit async job-135, details: AsyncJobVO {id:135, userId: 2, accountId: 2, instanceType: VirtualMachine, instanceId: 15, cmd: org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin, cmdInfo: {"id":"15","response":"json","serviceofferingid":"083d3f86-4b95-44c8-8361-0e571890ae2a","ctxDetails":"{\"interface com.cloud.vm.VirtualMachine\":\"d483480a-fa2d-47d2-ab9d-4355015f829e\",\"interface com.cloud.template.VirtualMachineTemplate\":\"7662eb72-791c-11e6-9aa7-00155dfa0a0e\",\"interface com.cloud.network.security.SecurityGroup\":\"07265180-791d-11e6-9aa7-00155dfa0a0e\",\"interface com.cloud.offering.ServiceOffering\":\"083d3f86-4b95-44c8-8361-0e571890ae2a\",\"interface com.cloud.dc.DataCenter\":\"baee96f3-fee4-4e18-9f44-428206d1b3f3\"}","cmdEventType":"VM.CREATE","ctxUserId":"2","zoneid":"baee96f3-fee4-4e18-9f44-428206d1b3f3","hypervisor":"XenServer","templateid":"7662eb72-791c-11e6-9aa7-00155dfa0a0e","httpmethod":"GET","_":"1474203217288","securitygroupids":"07265180-791d-11e6-9aa7-00155dfa0a0e","uuid":"d483480a-fa2d-47d2-ab9d-4355015f829e","ctxAccountId":"2","ctxStartEventId":"279"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 91770980878, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2016-09-18 14:55:37,232 DEBUG [c.c.a.ApiServlet] (catalina-exec-3:ctx-a5945e12 ctx-a27aa791) (logid:3dd43961) ===END=== 192.168.254.142 -- GET command=deployVirtualMachine&response=json&zoneid=baee96f3-fee4-4e18-9f44-428206d1b3f3&templateid=7662eb72-791c-11e6-9aa7-00155dfa0a0e&hypervisor=XenServer&serviceofferingid=083d3f86-4b95-44c8-8361-0e571890ae2a&securitygroupids=07265180-791d-11e6-9aa7-00155dfa0a0e&_=1474203217288
2016-09-18 14:55:37,236 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Executing AsyncJobVO {id:135, userId: 2, accountId: 2, instanceType: VirtualMachine, instanceId: 15, cmd: org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin, cmdInfo: {"id":"15","response":"json","serviceofferingid":"083d3f86-4b95-44c8-8361-0e571890ae2a","ctxDetails":"{\"interface com.cloud.vm.VirtualMachine\":\"d483480a-fa2d-47d2-ab9d-4355015f829e\",\"interface com.cloud.template.VirtualMachineTemplate\":\"7662eb72-791c-11e6-9aa7-00155dfa0a0e\",\"interface com.cloud.network.security.SecurityGroup\":\"07265180-791d-11e6-9aa7-00155dfa0a0e\",\"interface com.cloud.offering.ServiceOffering\":\"083d3f86-4b95-44c8-8361-0e571890ae2a\",\"interface com.cloud.dc.DataCenter\":\"baee96f3-fee4-4e18-9f44-428206d1b3f3\"}","cmdEventType":"VM.CREATE","ctxUserId":"2","zoneid":"baee96f3-fee4-4e18-9f44-428206d1b3f3","hypervisor":"XenServer","templateid":"7662eb72-791c-11e6-9aa7-00155dfa0a0e","httpmethod":"GET","_":"1474203217288","securitygroupids":"07265180-791d-11e6-9aa7-00155dfa0a0e","uuid":"d483480a-fa2d-47d2-ab9d-4355015f829e","ctxAccountId":"2","ctxStartEventId":"279"}, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 91770980878, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2016-09-18 14:55:37,278 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Deploy avoids pods: [], clusters: [], hosts: []
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner@4bfa7932
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Trying to allocate a host and storage pools from dc:1, pod:null,cluster:null, requested cpu: 1000, requested ram: 1073741824
2016-09-18 14:55:37,279 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Is ROOT volume READY (pool already allocated)?: No
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Searching all possible resources under this Zone: 1
2016-09-18 14:55:37,279 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Zone: 1
2016-09-18 14:55:37,282 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Removing from the clusterId list these clusters from avoid set: []
2016-09-18 14:55:37,288 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Checking resources in Cluster: 1 under Pod: 1
2016-09-18 14:55:37,288 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for hosts in dc: 1 pod:1 cluster:1
2016-09-18 14:55:37,290 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) FirstFitAllocator has 0 hosts to check for allocation: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) Found 0 hosts for allocation after prioritization: []
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) Looking for speed=1000Mhz, Ram=1024
2016-09-18 14:55:37,292 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50 FirstFitRoutingAllocator) (logid:c69c1ccd) Host Allocator returning 0 suitable hosts
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No suitable hosts found
2016-09-18 14:55:37,292 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No suitable hosts found under this Cluster: 1
2016-09-18 14:55:37,294 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Could not find suitable Deployment Destination for this VM under any clusters, returning.
2016-09-18 14:55:37,294 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Searching all possible resources under this Zone: 1
2016-09-18 14:55:37,295 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Zone: 1
2016-09-18 14:55:37,297 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Removing from the clusterId list these clusters from avoid set: [1]
2016-09-18 14:55:37,297 DEBUG [c.c.d.FirstFitPlanner] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) No clusters found after removing disabled clusters and clusters in avoid list, returning.
2016-09-18 14:55:37,299 DEBUG [c.c.v.UserVmManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Destroying vm VM[User|i-2-15-VM] as it failed to create on Host with Id:null
2016-09-18 14:55:37,311 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) 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
2016-09-18 14:55:37,329 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Access granted to Acct[07261698-791d-11e6-9aa7-00155dfa0a0e-admin] to Domain:1/ by AffinityGroupAccessChecker
2016-09-18 14:55:37,334 WARN [o.a.c.alerts] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) alertType:: 8 // dataCenterId:: 1 // podId:: null // clusterId:: null // message:: Failed to deploy Vm with Id: 15, on Host with Id: null
2016-09-18 14:55:37,356 INFO [o.a.c.a.c.a.v.DeployVMCmdByAdmin] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|i-2-15-VM]Scope=interface com.cloud.dc.DataCenter; id=1
2016-09-18 14:55:37,356 INFO [o.a.c.a.c.a.v.DeployVMCmdByAdmin] (API-Job-Executor-27:ctx-4b92f4ea job-135 ctx-fac5dc50) (logid:c69c1ccd) Unable to create a deployment for VM[User|i-2-15-VM]
com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|i-2-15-VM]Scope=interface com.cloud.dc.DataCenter; id=1
at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.reserveVirtualMachine(VMEntityManagerImpl.java:214)
at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.reserve(VirtualMachineEntityImpl.java:200)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:4092)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3710)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3698)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:106)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:51)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at com.sun.proxy.$Proxy198.startVirtualMachine(Unknown Source)
at org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin.execute(DeployVMCmdByAdmin.java:50)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150)
at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:108)
at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:554)
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:502)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
2016-09-18 14:55:37,356 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Complete async job-135, jobStatus: FAILED, resultCode: 530, result: org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":533,"errortext":"Unable to create a deployment for VM[User|i-2-15-VM]"}
2016-09-18 14:55:37,357 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Publish async job-135 complete on message bus
2016-09-18 14:55:37,357 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Wake up jobs related to job-135
2016-09-18 14:55:37,357 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Update db status for job-135
2016-09-18 14:55:37,359 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Wake up jobs joined with job-135 and disjoin all subjobs created from job- 135
2016-09-18 14:55:37,361 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Done executing org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin for job-135
2016-09-18 14:55:37,361 INFO [o.a.c.f.j.i.AsyncJobMonitor] (API-Job-Executor-27:ctx-4b92f4ea job-135) (logid:c69c1ccd) Remove job-135 from job monitoring
Jeroen Keerl
Keerl IT Services GmbH
Birkenstraße 1b . 21521 Aumühle
+49 177 6320 317
www.keerl-it.com<http://www.keerl-it.com/>
info@keerl-it.com<ma...@keerl-it.com>
Geschäftsführer. Jacobus J. Keerl
Registergericht Lubeck. HRB-Nr. 14511
Unsere Allgemeine Geschäftsbedingungen finden Sie hier.<http://www.keerl-it.com/AGB.pdf>
[cid:d3544f14.06fb964e.PNG.ab6bde08]