Hi, Can you share everything from the management log with `logid:0e77f2bc` because it's not the whole log for this call (StartVMCmdByAdmin)?
According to your shared log, no volumes are allocated to the pool. Also, is this VM already worked and then destroyed (without expunging)? What is the current state of the VM? Probably the deployment failed, and you tried to start the VM. If possible, can you try to deploy a new VM and share the log? Chris, the problem mentioned in the advisory shouldn't affect local storage. Best regards, Slavka On Sun, Aug 21, 2022 at 2:51 PM vas...@gmx.de <vas...@gmx.de> wrote: > HI, > > as a quick thought - have you seen the following advisorty > > https://blogs.apache.org/cloudstack/entry/advisory-apache-cloudstack-advisory-on > > also it would be helpful to see the agent logs from the host where the VM > shall be deployed to. > Regards, > Chris > > Am So., 21. Aug. 2022 um 13:09 Uhr schrieb Aufgabe Zwei < > aufgabez...@gmail.com>: > > > Hello, > > > > I would be grateful if you can assist or point me in the right direction. > > > > I have tried for almost a month to create a test deployment of cloudstack > > but each time I get to creating a vm instance, I get the error message > > > > Start instance > > > (VM1) Unable to create deployment, no usable volumes found for the VM: > > 1015 > > > > > > > I tried to find a fix on my own and have literally done a lot of > searching > > and reading around but I just can find the reason. > > > > I have ensured that the host, storage are correctly tagged. I have > enabled > > local storage and created local service offerings > > > > I followed these guides: > > > > Quick Installation Guide — Apache CloudStack 4.17.0.0 documentation > > < > > > http://docs.cloudstack.apache.org/en/latest/quickinstallationguide/qig.html#setting-up-a-zone > > > > > > > hackerbook/1-user.md at main · shapeblue/hackerbook · GitHub > > <https://github.com/shapeblue/hackerbook/blob/main/1-user.md#nfs-server> > > > > Thank you > > > > management log: > > > > > 2022-08-21 10:58:58,379 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb) > (logid:0e77f2bc) > > > Trying to allocate a host and storage pools from dc:1, > > > pod:null,cluster:null, requested cpu: 4500, requested ram: (2.00 GB) > > > 2147483648 > > > 2022-08-21 10:58:58,379 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb) > (logid:0e77f2bc) > > Is > > > ROOT volume READY (pool already allocated)?: No > > > 2022-08-21 10:58:58,381 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb) > (logid:0e77f2bc) > > > Deploy avoids pods: [], clusters: [], hosts: [] > > > 2022-08-21 10:58:58,381 DEBUG [c.c.d.FirstFitPlanner] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb) > (logid:0e77f2bc) > > > Searching all possible resources under this Zone: 1 > > > 2022-08-21 10:58:58,382 DEBUG [c.c.d.FirstFitPlanner] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb) > (logid:0e77f2bc) > > > Listing clusters in order of aggregate capacity, that have (at least > one > > > host with) enough CPU and RAM capacity under this Zone: 1 > > > 2022-08-21 10:58:58,383 DEBUG [c.c.d.FirstFitPlanner] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb) > (logid:0e77f2bc) > > > Removing from the clusterId list these clusters from avoid set: [] > > > 2022-08-21 10:58:58,384 DEBUG [c.c.d.FirstFitPlanner] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb) > (logid:0e77f2bc) > > > The clusterId list for the given offering tag: [1] > > > 2022-08-21 10:58:58,385 DEBUG [c.c.d.DeploymentPlanningManagerImpl] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb) > (logid:0e77f2bc) > > > Checking resources in Cluster: 1 under Pod: 1 > > > 2022-08-21 10:58:58,386 INFO [c.c.a.m.a.i.FirstFitAllocator] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > > FirstFitRoutingAllocator) (logid:0e77f2bc) Guest VM is requested with > > > Custom[UEFI] Boot Type false > > > 2022-08-21 10:58:58,386 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > > FirstFitRoutingAllocator) (logid:0e77f2bc) Looking for hosts in dc: 1 > > > pod:1 cluster:1 > > > 2022-08-21 10:58:58,386 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > > FirstFitRoutingAllocator) (logid:0e77f2bc) Looking for hosts having tag > > > specified on SvcOffering:local > > > 2022-08-21 10:58:58,386 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > > FirstFitRoutingAllocator) (logid:0e77f2bc) Hosts with tag 'local' > > are:[Host > > > {"id": "43", "name": "rs2", "uuid": > > "a3c2ef7e-d639-4dba-b727-e7bd0c371e4c", > > > "type"="Routing"}] > > > 2022-08-21 10:58:58,386 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > > FirstFitRoutingAllocator) (logid:0e77f2bc) FirstFitAllocator has 1 > hosts > > to > > > check for allocation: [Host {"id": "43", "name": "rs2", "uuid": > > > "a3c2ef7e-d639-4dba-b727-e7bd0c371e4c", "type"="Routing"}] > > > 2022-08-21 10:58:58,387 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > > FirstFitRoutingAllocator) (logid:0e77f2bc) Found 1 hosts for allocation > > > after prioritization: [Host {"id": "43", "name": "rs2", "uuid": > > > "a3c2ef7e-d639-4dba-b727-e7bd0c371e4c", "type"="Routing"}] > > > 2022-08-21 10:58:58,387 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > > FirstFitRoutingAllocator) (logid:0e77f2bc) Looking for speed=4500Mhz, > > > Ram=2048 MB > > > > 2022-08-21 10:58:58,387 DEBUG [c.c.c.CapacityManagerImpl] > > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > > FirstFitRoutingAllocator) (logid:0e77f2bc) Host {id: 43, name: rs2, > uuid: > > > a3c2ef7e-d639-4dba-b727-e7bd0c371e4c} is KVM hypervisor type, no max > > guest > > > limit check needed > > > > 2022-08-21 10:58:58,388 DEBUG [c.c.c.CapacityManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) Host: 43 has cpu capability > > (cpu:12, speed:3500) to support requested CPU: 3 and requested speed: > 1500 > > 2022-08-21 10:58:58,388 DEBUG [c.c.c.CapacityManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) Checking if host: 43 has > enough > > capacity for requested CPU: 4500 and requested RAM: (2.00 GB) 2147483648 > , > > cpuOverprovisioningFactor: 1.0 > > 2022-08-21 10:58:58,389 DEBUG [c.c.c.CapacityManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) Hosts's actual total CPU: > 42000 > > and CPU after applying overprovisioning: 42000 > > 2022-08-21 10:58:58,389 DEBUG [c.c.c.CapacityManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) Free CPU: 41000 , Requested > CPU: > > 4500 > > 2022-08-21 10:58:58,389 DEBUG [c.c.c.CapacityManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) Free RAM: (60.20 GB) > 64640737280 > > , Requested RAM: (2.00 GB) 2147483648 > > 2022-08-21 10:58:58,389 DEBUG [c.c.c.CapacityManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) Host has enough CPU and RAM > > available > > 2022-08-21 10:58:58,389 DEBUG [c.c.c.CapacityManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) STATS: Can alloc CPU from > host: > > 43, used: 1000, reserved: 0, actual total: 42000, total with > > overprovisioning: 42000; requested cpu:4500,alloc_from_last_host?:false > > ,considerReservedCapacity?: true > > 2022-08-21 10:58:58,389 DEBUG [c.c.c.CapacityManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) STATS: Can alloc MEM from > host: > > 43, used: (1.50 GB) 1610612736, reserved: (0 bytes) 0, total: (61.70 GB) > > 66251350016; requested mem: (2.00 GB) 2147483648, alloc_from_last_host?: > > false , considerReservedCapacity?: true > > 2022-08-21 10:58:58,389 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) Found a suitable host, adding > to > > list: 43 > > 2022-08-21 10:58:58,389 DEBUG [c.c.a.m.a.i.FirstFitAllocator] > > (API-Job-Executor-8:ctx-a898686b job-3069 ctx-2b5b16cb > > FirstFitRoutingAllocator) (logid:0e77f2bc) Host Allocator returning 1 > > suitable hosts > > 2022-08-21 10:58:58,402 ERROR [c.c.a.ApiAsyncJobDispatcher] > > (API-Job-Executor-8:ctx-a898686b job-3069) (logid:0e77f2bc) Unexpected > > exception while executing > > org.apache.cloudstack.api.command.admin.vm.StartVMCmdByAdmin > > com.cloud.utils.exception.CloudRuntimeException: Unable to create > > deployment, no usable volumes found for the VM: 1015 > > at > > > > > com.cloud.deploy.DeploymentPlanningManagerImpl.findSuitablePoolsForVolumes(DeploymentPlanningManagerImpl.java:1569) > > at > > > > > com.cloud.deploy.DeploymentPlanningManagerImpl.checkClustersforDestination(DeploymentPlanningManagerImpl.java:1217) > > at > > > > > com.cloud.deploy.DeploymentPlanningManagerImpl.planDeployment(DeploymentPlanningManagerImpl.java:527) > > at > > > > > org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.reserveVirtualMachine(VMEntityManagerImpl.java:203) > > at > > > > > org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.reserve(VirtualMachineEntityImpl.java:202) > > at > > > > > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:5280) > > at > > > > > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:5131) > > at > > > > > com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3115) > > at > > java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native > > Method) > > at > > > > > java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) > > at > > > > > java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) > > at java.base/java.lang.reflect.Method.invoke(Method.java:566) > > at > > > > > org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:344) > > at > > > > > org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:198) > > at > > > > > org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:163) > > at > > > > > org.apache.cloudstack.network.contrail.management.EventUtils$EventInterceptor.invoke(EventUtils.java:107) > > at > > > > > org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:175) > > at > > > > > com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:52) > > > > > > at > > > > > > org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:175) > > > > at > > > > > org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:97) > > at > > > > > org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186) > > at > > > > > org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:215) > > at com.sun.proxy.$Proxy128.startVirtualMachine(Unknown Source) > > at > > > > > org.apache.cloudstack.api.command.user.vm.StartVMCmd.execute(StartVMCmd.java:169) > > at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:163) > > at > > > com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:106) > > at > > > > > org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:620) > > at > > > > > org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:48) > > at > > > > > org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:55) > > at > > > > > org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:102) > > at > > > > > org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:52) > > at > > > > > org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:45) > > at > > > > > org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:568) > > at > > > > > java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515) > > at > > java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) > > at > > > > > java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128) > > at > > > > > java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628) > > at java.base/java.lang.Thread.run(Thread.java:829) > > 2022-08-21 10:58:58,402 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069) (logid:0e77f2bc) Complete > async > > job-3069, jobStatus: FAILED, resultCode: 530, result: > > > > > org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":"530","errortext":"Unable > > to create deployment, no usable volumes found for the VM: 1015"} > > 2022-08-21 10:58:58,402 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] > > (API-Job-Executor-8:ctx-a898686b job-3069) (logid:0e77f2bc) Publish async > > job-3069 complete on message bus > > > > > > > > >
