Return-Path: X-Original-To: archive-asf-public-internal@cust-asf2.ponee.io Delivered-To: archive-asf-public-internal@cust-asf2.ponee.io Received: from cust-asf.ponee.io (cust-asf.ponee.io [163.172.22.183]) by cust-asf2.ponee.io (Postfix) with ESMTP id 28CFC200C2D for ; Sat, 4 Mar 2017 21:48:00 +0100 (CET) Received: by cust-asf.ponee.io (Postfix) id 274F5160B71; Sat, 4 Mar 2017 20:48:00 +0000 (UTC) Delivered-To: archive-asf-public@cust-asf.ponee.io Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by cust-asf.ponee.io (Postfix) with SMTP id A5750160B61 for ; Sat, 4 Mar 2017 21:47:58 +0100 (CET) Received: (qmail 93412 invoked by uid 500); 4 Mar 2017 20:47:57 -0000 Mailing-List: contact issues-help@cloudstack.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@cloudstack.apache.org Delivered-To: mailing list issues@cloudstack.apache.org Received: (qmail 93403 invoked by uid 500); 4 Mar 2017 20:47:57 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 93400 invoked by uid 99); 4 Mar 2017 20:47:57 -0000 Received: from pnap-us-west-generic-nat.apache.org (HELO spamd2-us-west.apache.org) (209.188.14.142) by apache.org (qpsmtpd/0.29) with ESMTP; Sat, 04 Mar 2017 20:47:57 +0000 Received: from localhost (localhost [127.0.0.1]) by spamd2-us-west.apache.org (ASF Mail Server at spamd2-us-west.apache.org) with ESMTP id 3D9491A055C for ; Sat, 4 Mar 2017 20:47:57 +0000 (UTC) X-Virus-Scanned: Debian amavisd-new at spamd2-us-west.apache.org X-Spam-Flag: NO X-Spam-Score: -1.546 X-Spam-Level: X-Spam-Status: No, score=-1.546 tagged_above=-999 required=6.31 tests=[KAM_ASCII_DIVIDERS=0.8, RP_MATCHES_RCVD=-2.999, SPF_NEUTRAL=0.652, URIBL_BLOCKED=0.001] autolearn=disabled Received: from mx1-lw-eu.apache.org ([10.40.0.8]) by localhost (spamd2-us-west.apache.org [10.40.0.9]) (amavisd-new, port 10024) with ESMTP id JLzWhANVp3-r for ; Sat, 4 Mar 2017 20:47:51 +0000 (UTC) Received: from mailrelay1-us-west.apache.org (mailrelay1-us-west.apache.org [209.188.14.139]) by mx1-lw-eu.apache.org (ASF Mail Server at mx1-lw-eu.apache.org) with ESMTP id AA2C15FACA for ; Sat, 4 Mar 2017 20:47:50 +0000 (UTC) Received: from jira-lw-us.apache.org (unknown [207.244.88.139]) by mailrelay1-us-west.apache.org (ASF Mail Server at mailrelay1-us-west.apache.org) with ESMTP id 72E12E02BE for ; Sat, 4 Mar 2017 20:47:46 +0000 (UTC) Received: from jira-lw-us.apache.org (localhost [127.0.0.1]) by jira-lw-us.apache.org (ASF Mail Server at jira-lw-us.apache.org) with ESMTP id 58F802415C for ; Sat, 4 Mar 2017 20:47:45 +0000 (UTC) Date: Sat, 4 Mar 2017 20:47:45 +0000 (UTC) From: "ASF GitHub Bot (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (CLOUDSTACK-9604) Root disk resize support for VMware and XenServer MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 archived-at: Sat, 04 Mar 2017 20:48:00 -0000 [ https://issues.apache.org/jira/browse/CLOUDSTACK-9604?page=3Dcom.atla= ssian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId= =3D15895866#comment-15895866 ]=20 ASF GitHub Bot commented on CLOUDSTACK-9604: -------------------------------------------- Github user borisstoyanov commented on the issue: https://github.com/apache/cloudstack/pull/1813 =20 Hi @serg38 ,=20 =20 Here's the management log:=20 ``` 2017-03-04 20:43:40,972 INFO [c.c.h.v.r.VmwareResource] (DirectAgent-2= 49:ctx-585f426d 10.2.2.73, job-29/job-32, cmd: StartCommand) (logid:b0dca52= 6) Found existing disk info from volume path: ROOT-3 2017-03-04 20:43:40,972 ERROR [c.c.h.v.r.VmwareResource] (DirectAgent-2= 49:ctx-585f426d 10.2.2.73, job-29/job-32, cmd: StartCommand) (logid:b0dca52= 6) Unsupported Disk chain length 2 2017-03-04 20:43:40,972 INFO [c.c.h.v.u.VmwareHelper] (DirectAgent-249= :ctx-585f426d 10.2.2.73, job-29/job-32, cmd: StartCommand) (logid:b0dca526)= [ignored]failed toi get message for exception: Unsupported Disk chain leng= th 2 2017-03-04 20:43:40,975 WARN [c.c.h.v.r.VmwareResource] (DirectAgent-2= 49:ctx-585f426d 10.2.2.73, job-29/job-32, cmd: StartCommand) (logid:b0dca52= 6) StartCommand failed due to Exception: java.lang.Exception Message: Unsupported Disk chain length 2 =20 java.lang.Exception: Unsupported Disk chain length 2 =09at com.cloud.hypervisor.vmware.resource.VmwareResource.resizeRootDis= k(VmwareResource.java:2106) =09at com.cloud.hypervisor.vmware.resource.VmwareResource.execute(Vmwar= eResource.java:2029) =09at com.cloud.hypervisor.vmware.resource.VmwareResource.executeReques= t(VmwareResource.java:467) =09at com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(Dire= ctAgentAttache.java:315) =09at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.ru= n(ManagedContextRunnable.java:49) =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$= 1.call(DefaultManagedContext.java:56) =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.= callWithContext(DefaultManagedContext.java:103) =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.= runWithContext(DefaultManagedContext.java:53) =09at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(= ManagedContextRunnable.java:46) =09at java.util.concurrent.Executors$RunnableAdapter.call(Executors.jav= a:511) =09at java.util.concurrent.FutureTask.run(FutureTask.java:266) =09at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureT= ask.access$201(ScheduledThreadPoolExecutor.java:180) =09at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureT= ask.run(ScheduledThreadPoolExecutor.java:293) =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecu= tor.java:1142) =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExec= utor.java:617) =09at java.lang.Thread.run(Thread.java:745) 2017-03-04 20:43:40,975 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent= -249:ctx-585f426d) (logid:b0dca526) Seq 1-6353453174312927730: Response Rec= eived: 2017-03-04 20:43:40,976 DEBUG [c.c.a.t.Request] (DirectAgent-249:ctx-58= 5f426d) (logid:b0dca526) Seq 1-6353453174312927730: Processing: { Ans: , M= gmtId: 7267420276525, via: 1(10.2.2.73), Ver: v1, Flags: 10, [{"com.cloud.a= gent.api.StartAnswer":{"vm":{"id":3,"name":"i-4-3-VM","bootloader":"HVM","t= ype":"User","cpus":1,"minSpeed":50,"maxSpeed":100,"minRam":268435456,"maxRa= m":268435456,"hostName":"testvm","arch":"x86_64","os":"CentOS 5.3 (64-bit)"= ,"platformEmulator":"centos64Guest","bootArgs":"","enableHA":false,"limitCp= uUse":false,"enableDynamicallyScaleVm":false,"vncPassword":"pztIl7EL8AvGJtk= rL8TuMQ","params":{"deployvm":"true","dataDiskController":"osdefault","memo= ryOvercommitRatio":"1.0","Message.ReservedCapacityFreed.Flag":"false","nest= edVirtualizationFlag":"false","rootdisksize":"4","cpuOvercommitRatio":"2.0"= ,"vmware.reserve.mem":"false","vmware.reserve.cpu":"false","nicAdapter":"E1= 000","rootDiskController":"ide"},"uuid":"125b41bb-2d1f-4ab5-8bc4-493f4873fe= 86","disks":[{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"u= uid":"abf2b785-936b-40f8-91b0-0e7f62d3620f","volumeType":"ROOT","dataStore"= :{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"bdcf18cf-7= 9d5-3459-a681-225d15719513","id":1,"poolType":"NetworkFilesystem","host":"1= 0.2.0.16","path":"/acs/primary/pr1813-t931-vmware-55u3/pr1813-t931-vmware-5= 5u3-esxi-pri1","port":2049,"url":"NetworkFilesystem://10.2.0.16/acs/primary= /pr1813-t931-vmware-55u3/pr1813-t931-vmware-55u3-esxi-pri1/?ROLE=3DPrimary&= STOREUUID=3Dbdcf18cf-79d5-3459-a681-225d15719513","isManaged":false}},"name= ":"ROOT-3","size":4294967296,"path":"ROOT-3","volumeId":3,"vmName":"i-4-3-V= M","accountId":4,"format":"OVA","provisioningType":"THIN","id":3,"deviceId"= :0,"bytesReadRate":0,"bytesWriteRate":0,"iopsReadRate":0,"iopsWriteRate":0,= "hypervisorType":"VMware"}},"diskSeq":0,"path":"ROOT-3","type":"ROOT","_det= ails":{"storageHost":"10.2.0.16","managed":"false","storagePort":"2049","vo= lumeSize":"4294967296"}},{"data":{"org.apache.cloudstack.storage.to.Templat= eObjectTO":{"id":0,"format":"ISO","accountId":0,"hvm":false}},"diskSeq":3,"= type":"ISO"}],"nics":[{"deviceId":0,"networkRateMbps":200,"defaultNic":true= ,"pxeDisable":false,"nicUuid":"1de3e11d-057c-4d5d-9a06-0bed9686efbd","uuid"= :"ed0b21f4-04a2-442d-ba0b-3c6276f3e96a","ip":"10.1.1.234","netmask":"255.25= 5.255.0","gateway":"10.1.1.1","mac":"02:00:38:f0:00:01","dns1":"8.8.8.8","d= ns2":"8.8.4.4","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan:/= /889","isolationUri":"vlan://889","isSecurityGroupEnabled":false,"name":"vS= witch1,,vmwaresvs"}]},"result":false,"details":"StartCommand failed due to = Exception: java.lang.Exception\nMessage: Unsupported Disk chain length 2\n"= ,"wait":0}}] } 2017-03-04 20:43:40,976 DEBUG [c.c.a.t.Request] (Work-Job-Executor-4:ct= x-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Seq 1-6353453174312= 927730: Received: { Ans: , MgmtId: 7267420276525, via: 1(10.2.2.73), Ver: = v1, Flags: 10, { StartAnswer } } 2017-03-04 20:43:40,979 INFO [c.c.v.VirtualMachineManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Una= ble to start VM on Host[-1-Routing] due to StartCommand failed due to Excep= tion: java.lang.Exception Message: Unsupported Disk chain length 2 =20 2017-03-04 20:43:40,982 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Cle= aning up resources for the vm VM[User|i-4-3-VM] in Starting state 2017-03-04 20:43:40,983 DEBUG [c.c.a.t.Request] (Work-Job-Executor-4:ct= x-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Seq 1-6353453174312= 927733: Sending { Cmd , MgmtId: 7267420276525, via: 1(10.2.2.73), Ver: v1,= Flags: 100011, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"check= BeforeCleanup":false,"vmName":"i-4-3-VM","executeInSequence":false,"wait":0= }}] } 2017-03-04 20:43:40,983 DEBUG [c.c.a.t.Request] (Work-Job-Executor-4:ct= x-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Seq 1-6353453174312= 927733: Executing: { Cmd , MgmtId: 7267420276525, via: 1(10.2.2.73), Ver: = v1, Flags: 100011, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"ch= eckBeforeCleanup":false,"vmName":"i-4-3-VM","executeInSequence":false,"wait= ":0}}] } 2017-03-04 20:43:40,983 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent= -63:ctx-6366c90c) (logid:69a12df2) Seq 1-6353453174312927733: Executing req= uest 2017-03-04 20:43:40,983 INFO [c.c.h.v.r.VmwareResource] (DirectAgent-6= 3:ctx-6366c90c 10.2.2.73, job-29/job-32, cmd: StopCommand) (logid:b0dca526)= Executing resource StopCommand: {"isProxy":false,"checkBeforeCleanup":fals= e,"vmName":"i-4-3-VM","executeInSequence":false,"wait":0} 2017-03-04 20:43:40,994 DEBUG [c.c.h.v.m.HostMO] (DirectAgent-63:ctx-63= 66c90c 10.2.2.73, job-29/job-32, cmd: StopCommand) (logid:b0dca526) find VM= i-4-3-VM on host 2017-03-04 20:43:40,994 INFO [c.c.h.v.m.HostMO] (DirectAgent-63:ctx-63= 66c90c 10.2.2.73, job-29/job-32, cmd: StopCommand) (logid:b0dca526) VM i-4-= 3-VM not found in host cache 2017-03-04 20:43:40,994 DEBUG [c.c.h.v.m.HostMO] (DirectAgent-63:ctx-63= 66c90c 10.2.2.73, job-29/job-32, cmd: StopCommand) (logid:b0dca526) load VM= cache on host 2017-03-04 20:43:41,043 INFO [c.c.h.v.r.VmwareResource] (DirectAgent-6= 3:ctx-6366c90c 10.2.2.73, job-29/job-32, cmd: StopCommand) (logid:b0dca526)= VM i-4-3-VM is already in stopped state 2017-03-04 20:43:41,043 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent= -63:ctx-6366c90c) (logid:b0dca526) Seq 1-6353453174312927733: Response Rece= ived: 2017-03-04 20:43:41,043 DEBUG [c.c.a.t.Request] (DirectAgent-63:ctx-636= 6c90c) (logid:b0dca526) Seq 1-6353453174312927733: Processing: { Ans: , Mg= mtId: 7267420276525, via: 1(10.2.2.73), Ver: v1, Flags: 10, [{"com.cloud.ag= ent.api.StopAnswer":{"result":true,"details":"VM i-4-3-VM is already in sto= pped state","wait":0}}] } 2017-03-04 20:43:41,043 DEBUG [c.c.a.t.Request] (Work-Job-Executor-4:ct= x-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Seq 1-6353453174312= 927733: Received: { Ans: , MgmtId: 7267420276525, via: 1(10.2.2.73), Ver: = v1, Flags: 10, { StopAnswer } } 2017-03-04 20:43:41,047 DEBUG [c.c.n.NetworkModelImpl] (Work-Job-Execut= or-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Service Secu= rityGroup is not supported in the network id=3D204 2017-03-04 20:43:41,048 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Work-Job= -Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Chang= ing active number of nics for network id=3D204 on -1 2017-03-04 20:43:41,053 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Work-Job= -Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Askin= g VirtualRouter to release NicProfile[7-3-63bdc82b-0377-455e-8015-940282d98= 400-10.1.1.234-null 2017-03-04 20:43:41,053 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Suc= cessfully released network resources for the vm VM[User|i-4-3-VM] 2017-03-04 20:43:41,053 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Suc= cessfully cleanued up resources for the vm VM[User|i-4-3-VM] in Starting st= ate 2017-03-04 20:43:41,054 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Roo= t volume is ready, need to place VM in volume's cluster 2017-03-04 20:43:41,054 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Vol= [3|vm=3D3|ROOT] is READY, changing deployment plan to use this pool's dcId:= 1 , podId: 1 , and clusterId: 1 2017-03-04 20:43:41,058 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= Deploy avoids pods: [], clusters: [], hosts: [1] 2017-03-04 20:43:41,058 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner@2= 3d20e4e 2017-03-04 20:43:41,058 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, re= quested cpu: 100, requested ram: 268435456 2017-03-04 20:43:41,058 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= Is ROOT volume READY (pool already allocated)?: Yes 2017-03-04 20:43:41,058 DEBUG [c.c.d.FirstFitPlanner] (Work-Job-Executo= r-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Searching res= ources only under specified Cluster: 1 2017-03-04 20:43:41,063 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= Checking resources in Cluster: 1 under Pod: 1 2017-03-04 20:43:41,063 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Work-Job= -Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea FirstFitRoutingAllocato= r) (logid:b0dca526) Looking for hosts in dc: 1 pod:1 cluster:1 2017-03-04 20:43:41,064 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Work-Job= -Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea FirstFitRoutingAllocato= r) (logid:b0dca526) FirstFitAllocator has 1 hosts to check for allocation: = [Host[-1-Routing]] 2017-03-04 20:43:41,066 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Work-Job= -Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea FirstFitRoutingAllocato= r) (logid:b0dca526) Found 1 hosts for allocation after prioritization: [Hos= t[-1-Routing]] 2017-03-04 20:43:41,066 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Work-Job= -Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea FirstFitRoutingAllocato= r) (logid:b0dca526) Looking for speed=3D100Mhz, Ram=3D256 2017-03-04 20:43:41,066 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Work-Job= -Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea FirstFitRoutingAllocato= r) (logid:b0dca526) Host name: 10.2.2.73, hostId: 1 is in avoid set, skippi= ng this and trying other available hosts 2017-03-04 20:43:41,066 DEBUG [c.c.a.m.a.i.FirstFitAllocator] (Work-Job= -Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea FirstFitRoutingAllocato= r) (logid:b0dca526) Host Allocator returning 0 suitable hosts 2017-03-04 20:43:41,066 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= No suitable hosts found 2017-03-04 20:43:41,066 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= No suitable hosts found under this Cluster: 1 2017-03-04 20:43:41,067 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= Could not find suitable Deployment Destination for this VM under any clust= ers, returning. 2017-03-04 20:43:41,067 DEBUG [c.c.d.FirstFitPlanner] (Work-Job-Executo= r-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Searching res= ources only under specified Cluster: 1 2017-03-04 20:43:41,067 DEBUG [c.c.d.FirstFitPlanner] (Work-Job-Executo= r-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) The specified= cluster is in avoid set, returning. 2017-03-04 20:43:41,070 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= Deploy avoids pods: [], clusters: [1], hosts: [1] 2017-03-04 20:43:41,071 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner@2= 3d20e4e 2017-03-04 20:43:41,071 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= Trying to allocate a host and storage pools from dc:1, pod:1,cluster:null,= requested cpu: 100, requested ram: 268435456 2017-03-04 20:43:41,071 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Wo= rk-Job-Executor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526)= Is ROOT volume READY (pool already allocated)?: No 2017-03-04 20:43:41,071 DEBUG [c.c.d.FirstFitPlanner] (Work-Job-Executo= r-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Searching res= ources only under specified Pod: 1 2017-03-04 20:43:41,071 DEBUG [c.c.d.FirstFitPlanner] (Work-Job-Executo= r-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Listing clust= ers in order of aggregate capacity, that have (atleast one host with) enoug= h CPU and RAM capacity under this Pod: 1 2017-03-04 20:43:41,073 DEBUG [c.c.d.FirstFitPlanner] (Work-Job-Executo= r-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Removing from= the clusterId list these clusters from avoid set: [1] 2017-03-04 20:43:41,074 DEBUG [c.c.d.FirstFitPlanner] (Work-Job-Executo= r-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) No clusters f= ound after removing disabled clusters and clusters in avoid list, returning= . 2017-03-04 20:43:41,083 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Exe= cutor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) VM state = transitted from :Starting to Stopped with event: OperationFailedvm's origin= al host id: null new host id: null host id before state transition: 1 2017-03-04 20:43:41,086 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Exe= cutor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Hosts's a= ctual total CPU: 4797 and CPU after applying overprovisioning: 9594 2017-03-04 20:43:41,086 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Exe= cutor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Hosts's a= ctual total RAM: 8589398016 and RAM after applying overprovisioning: 858939= 8016 2017-03-04 20:43:41,086 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Exe= cutor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) release c= pu from host: 1, old used: 1600,reserved: 0, actual total: 4797, total with= overprovisioning: 9594; new used: 1500,reserved:0; movedfromreserved: fals= e,moveToReserveredfalse 2017-03-04 20:43:41,086 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Exe= cutor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) release m= em from host: 1, old used: 2147483648,reserved: 0, total: 8589398016; new u= sed: 1879048192,reserved:0; movedfromreserved: false,moveToReserveredfalse 2017-03-04 20:43:41,096 ERROR [c.c.v.VmWorkJobHandlerProxy] (Work-Job-E= xecutor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Invocat= ion exception, caused by: com.cloud.exception.InsufficientServerCapacityExc= eption: Unable to create a deployment for VM[User|i-4-3-VM]Scope=3Dinterfac= e com.cloud.dc.DataCenter; id=3D1 2017-03-04 20:43:41,096 INFO [c.c.v.VmWorkJobHandlerProxy] (Work-Job-E= xecutor-4:ctx-56cda672 job-29/job-32 ctx-cf9f4cea) (logid:b0dca526) Rethrow= exception com.cloud.exception.InsufficientServerCapacityException: Unable = to create a deployment for VM[User|i-4-3-VM]Scope=3Dinterface com.cloud.dc.= DataCenter; id=3D1 2017-03-04 20:43:41,096 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Exe= cutor-4:ctx-56cda672 job-29/job-32) (logid:b0dca526) Done with run of VM wo= rk job: com.cloud.vm.VmWorkStart for VM 3, job origin: 29 2017-03-04 20:43:41,096 ERROR [c.c.v.VmWorkJobDispatcher] (Work-Job-Exe= cutor-4:ctx-56cda672 job-29/job-32) (logid:b0dca526) Unable to complete Asy= ncJobVO {id:32, userId: 2, accountId: 2, instanceType: null, instanceId: nu= ll, cmd: com.cloud.vm.VmWorkStart, cmdInfo: rO0ABXNyABhjb20uY2xvdWQudm0uVm1= Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZhdm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS= 9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAljbHVzdGVySWR0ABBMamF2YS9sYW5nL= 0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxqYXZhL2xhbmcvU3RyaW5nO0wA= EXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVwb2RJZHEAfgACTAAGcG9= vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNlcnZhdGlvbklkcQ= B-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlc= klkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAAACAAAAAAAAAAIAAAAAAAAAA3QA= GVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAHBwcHBwcHBwc3IAEWphdmEudXRpbC5= IYXNoTWFwBQfawcMWYNEDAAJGAApsb2FkRmFjdG9ySQAJdGhyZXNob2xkeHA_QAAAAAAADHcIAA= AAEAAAAAF0AApWbVBhc3N3b3JkdAAcck8wQUJYUUFEbk5oZG1Wa1gzQmhjM04zYjNKa3hw, cmd= Version: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: n= ull, initMsid: 7267420276525, completeMsid: null, lastUpdated: null, lastPo= lled: null, created: Sat Mar 04 20:43:27 UTC 2017}, job origin:29 com.cloud.exception.InsufficientServerCapacityException: Unable to crea= te a deployment for VM[User|i-4-3-VM]Scope=3Dinterface com.cloud.dc.DataCen= ter; id=3D1 =09at com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMa= chineManagerImpl.java:961) =09at com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMa= chineManagerImpl.java:4661) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorI= mpl.java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodA= ccessorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandl= erProxy.java:107) =09at com.cloud.vm.VirtualMachineManagerImpl.handleVmWorkJob(VirtualMac= hineManagerImpl.java:4822) =09at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:= 102) =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.r= unInContext(AsyncJobManagerImpl.java:554) =09at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.ru= n(ManagedContextRunnable.java:49) =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$= 1.call(DefaultManagedContext.java:56) =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.= callWithContext(DefaultManagedContext.java:103) =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.= runWithContext(DefaultManagedContext.java:53) =09at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(= ManagedContextRunnable.java:46) =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.r= un(AsyncJobManagerImpl.java:502) =09at java.util.concurrent.Executors$RunnableAdapter.call(Executors.jav= a:511) =09at java.util.concurrent.FutureTask.run(FutureTask.java:266) =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecu= tor.java:1142) =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExec= utor.java:617) =09at java.lang.Thread.run(Thread.java:745) 2017-03-04 20:43:41,098 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32) (logid:b0dca526) Complete async j= ob-32, jobStatus: FAILED, resultCode: 0, result: rO0ABXNyADdjb20uY2xvdWQuZX= hjZXB0aW9uLkluc3VmZmljaWVudFNlcnZlckNhcGFjaXR5RXhjZXB0aW9uAAAAAFZNT3ACAAFaA= BVhZmZpbml0eUdyb3Vwc0FwcGxpZWR4cgAxY29tLmNsb3VkLmV4Y2VwdGlvbi5JbnN1ZmZpY2ll= bnRDYXBhY2l0eUV4Y2VwdGlvbgAAAABWTU91AgACTAACaWR0ABBMamF2YS9sYW5nL0xvbmc7TAA= Fc2NvcGV0ABFMamF2YS9sYW5nL0NsYXNzO3hyACJjb20uY2xvdWQuZXhjZXB0aW9uLkNsb3VkRX= hjZXB0aW9ueeiOG_HkRMcCAAJMAAtjc0Vycm9yQ29kZXQAE0xqYXZhL2xhbmcvSW50ZWdlcjtMA= AZpZExpc3R0ABVMamF2YS91dGlsL0FycmF5TGlzdDt4cgATamF2YS5sYW5nLkV4Y2VwdGlvbtD9= Hz4aOxzEAgAAeHIAE2phdmEubGFuZy5UaHJvd2FibGXVxjUnOXe4ywMABEwABWNhdXNldAAVTGp= hdmEvbGFuZy9UaHJvd2FibGU7TAANZGV0YWlsTWVzc2FnZXQAEkxqYXZhL2xhbmcvU3RyaW5nO1= sACnN0YWNrVHJhY2V0AB5bTGphdmEvbGFuZy9TdGFja1RyYWNlRWxlbWVudDtMABRzdXBwcmVzc= 2VkRXhjZXB0aW9uc3QAEExqYXZhL3V0aWwvTGlzdDt4cHEAfgANdAAzVW5hYmxlIHRvIGNyZWF0= ZSBhIGRlcGxveW1lbnQgZm9yIFZNW1VzZXJ8aS00LTMtVk1ddXIAHltMamF2YS5sYW5nLlN0YWN= rVHJhY2VFbGVtZW50OwJGKjw8_SI5AgAAeHAAAAAVc3IAG2phdmEubGFuZy5TdGFja1RyYWNlRW= xlbWVudGEJxZomNt2FAgAESQAKbGluZU51bWJlckwADmRlY2xhcmluZ0NsYXNzcQB-AApMAAhma= WxlTmFtZXEAfgAKTAAKbWV0aG9kTmFtZXEAfgAKeHAAAAPBdAAmY29tLmNsb3VkLnZtLlZpcnR1= YWxNYWNoaW5lTWFuYWdlckltcGx0AB5WaXJ0dWFsTWFjaGluZU1hbmFnZXJJbXBsLmphdmF0ABB= vcmNoZXN0cmF0ZVN0YXJ0c3EAfgARAAASNXEAfgATcQB-ABRxAH4AFXNxAH4AEf____50ACRzdW= 4ucmVmbGVjdC5OYXRpdmVNZXRob2RBY2Nlc3NvckltcGx0AB1OYXRpdmVNZXRob2RBY2Nlc3Nvc= kltcGwuamF2YXQAB2ludm9rZTBzcQB-ABEAAAA-cQB-ABhxAH4AGXQABmludm9rZXNxAH4AEQAA= ACt0AChzdW4ucmVmbGVjdC5EZWxlZ2F0aW5nTWV0aG9kQWNjZXNzb3JJbXBsdAAhRGVsZWdhdGl= uZ01ldGhvZEFjY2Vzc29ySW1wbC5qYXZhcQB-ABxzcQB-ABEAAAHydAAYamF2YS5sYW5nLnJlZm= xlY3QuTWV0aG9kdAALTWV0aG9kLmphdmFxAH4AHHNxAH4AEQAAAGt0ACJjb20uY2xvdWQudm0uV= m1Xb3JrSm9iSGFuZGxlclByb3h5dAAaVm1Xb3JrSm9iSGFuZGxlclByb3h5LmphdmF0AA9oYW5k= bGVWbVdvcmtKb2JzcQB-ABEAABLWcQB-ABNxAH4AFHEAfgAmc3EAfgARAAAAZnQAIGNvbS5jbG9= 1ZC52bS5WbVdvcmtKb2JEaXNwYXRjaGVydAAYVm1Xb3JrSm9iRGlzcGF0Y2hlci5qYXZhdAAGcn= VuSm9ic3EAfgARAAACKnQAP29yZy5hcGFjaGUuY2xvdWRzdGFjay5mcmFtZXdvcmsuam9icy5pb= XBsLkFzeW5jSm9iTWFuYWdlckltcGwkNXQAGEFzeW5jSm9iTWFuYWdlckltcGwuamF2YXQADHJ1= bkluQ29udGV4dHNxAH4AEQAAADF0AD5vcmcuYXBhY2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb25= 0ZXh0Lk1hbmFnZWRDb250ZXh0UnVubmFibGUkMXQAG01hbmFnZWRDb250ZXh0UnVubmFibGUuam= F2YXQAA3J1bnNxAH4AEQAAADh0AEJvcmcuYXBhY2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb250Z= Xh0LmltcGwuRGVmYXVsdE1hbmFnZWRDb250ZXh0JDF0ABpEZWZhdWx0TWFuYWdlZENvbnRleHQu= amF2YXQABGNhbGxzcQB-ABEAAABndABAb3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29= udGV4dC5pbXBsLkRlZmF1bHRNYW5hZ2VkQ29udGV4dHEAfgA2dAAPY2FsbFdpdGhDb250ZXh0c3= EAfgARAAAANXEAfgA5cQB-ADZ0AA5ydW5XaXRoQ29udGV4dHNxAH4AEQAAAC50ADxvcmcuYXBhY= 2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb250ZXh0Lk1hbmFnZWRDb250ZXh0UnVubmFibGVxAH4A= MnEAfgAzc3EAfgARAAAB9nEAfgAtcQB-AC5xAH4AM3NxAH4AEQAAAf90AC5qYXZhLnV0aWwuY29= uY3VycmVudC5FeGVjdXRvcnMkUnVubmFibGVBZGFwdGVydAAORXhlY3V0b3JzLmphdmFxAH4AN3= NxAH4AEQAAAQp0AB9qYXZhLnV0aWwuY29uY3VycmVudC5GdXR1cmVUYXNrdAAPRnV0dXJlVGFza= y5qYXZhcQB-ADNzcQB-ABEAAAR2dAAnamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4= ZWN1dG9ydAAXVGhyZWFkUG9vbEV4ZWN1dG9yLmphdmF0AAlydW5Xb3JrZXJzcQB-ABEAAAJpdAA= uamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9yJFdvcmtlcnEAfgBIcQB-AD= NzcQB-ABEAAALpdAAQamF2YS5sYW5nLlRocmVhZHQAC1RocmVhZC5qYXZhcQB-ADNzcgAmamF2Y= S51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJsZUxpc3T8DyUxteyOEAIAAUwABGxpc3RxAH4A= DHhyACxqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlQ29sbGVjdGlvbhlCAIDLXvc= eAgABTAABY3QAFkxqYXZhL3V0aWwvQ29sbGVjdGlvbjt4cHNyABNqYXZhLnV0aWwuQXJyYXlMaX= N0eIHSHZnHYZ0DAAFJAARzaXpleHAAAAAAdwQAAAAAeHEAfgBUeHNyABFqYXZhLmxhbmcuSW50Z= WdlchLioKT3gYc4AgABSQAFdmFsdWV4cgAQamF2YS5sYW5nLk51bWJlcoaslR0LlOCLAgAAeHAA= ABDvc3EAfgBTAAAAAHcEAAAAAHhzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHV= leHEAfgBWAAAAAAAAAAF2cgAXY29tLmNsb3VkLmRjLkRhdGFDZW50ZXKy8Qrrp-d4vgIAAHhwAA 2017-03-04 20:43:41,098 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32) (logid:b0dca526) Publish async jo= b-32 complete on message bus 2017-03-04 20:43:41,098 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32) (logid:b0dca526) Wake up jobs rel= ated to job-32 2017-03-04 20:43:41,098 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32) (logid:b0dca526) Update db status= for job-32 2017-03-04 20:43:41,099 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32) (logid:b0dca526) Wake up jobs joi= ned with job-32 and disjoin all subjobs created from job- 32 2017-03-04 20:43:41,105 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-J= ob-Executor-4:ctx-56cda672 job-29/job-32) (logid:b0dca526) Done executing c= om.cloud.vm.VmWorkStart for job-32 2017-03-04 20:43:41,107 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-E= xecutor-4:ctx-56cda672 job-29/job-32) (logid:b0dca526) Remove job-32 from j= ob monitoring 2017-03-04 20:43:41,113 DEBUG [c.c.v.UserVmManagerImpl] (API-Job-Execut= or-23:ctx-0c7ca79a job-29 ctx-f34539ef) (logid:b0dca526) Destroying vm VM[U= ser|i-4-3-VM] as it failed to create on Host with Id:null 2017-03-04 20:43:41,120 DEBUG [c.c.c.CapacityManagerImpl] (API-Job-Exec= utor-23:ctx-0c7ca79a job-29 ctx-f34539ef) (logid:b0dca526) VM state transit= ted from :Stopped to Error with event: OperationFailedToErrorvm's original = host id: null new host id: null host id before state transition: null 2017-03-04 20:43:41,151 DEBUG [c.c.u.AccountManagerImpl] (API-Job-Execu= tor-23:ctx-0c7ca79a job-29 ctx-f34539ef) (logid:b0dca526) Access granted to= Acct[eb82ac50-0109-11e7-af92-069c1401072d-admin] to Domain:1/ by AffinityG= roupAccessChecker 2017-03-04 20:43:41,160 WARN [o.a.c.alerts] (API-Job-Executor-23:ctx-0= c7ca79a job-29 ctx-f34539ef) (logid:b0dca526) alertType:: 8 // dataCenterI= d:: 1 // podId:: 1 // clusterId:: null // message:: Failed to deploy Vm wit= h Id: 3, on Host with Id: null 2017-03-04 20:43:41,180 ERROR [c.c.a.ApiAsyncJobDispatcher] (API-Job-Ex= ecutor-23:ctx-0c7ca79a job-29) (logid:b0dca526) Unexpected exception while = executing org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin com.cloud.utils.exception.CloudRuntimeException: Unable to start a VM d= ue to insufficient capacity =09at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManage= rImpl.java:623) =09at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl= .deployVirtualMachine(VMEntityManagerImpl.java:242) =09at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntit= yImpl.deploy(VirtualMachineEntityImpl.java:212) =09at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerI= mpl.java:4097) =09at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerI= mpl.java:3695) =09at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerI= mpl.java:3683) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorI= mpl.java:62) =09at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodA= ccessorImpl.java:43) =09at java.lang.reflect.Method.invoke(Method.java:498) =09at org.springframework.aop.support.AopUtils.invokeJoinpointUsingRefl= ection(AopUtils.java:333) =09at org.springframework.aop.framework.ReflectiveMethodInvocation.invo= keJoinpoint(ReflectiveMethodInvocation.java:190) =09at org.springframework.aop.framework.ReflectiveMethodInvocation.proc= eed(ReflectiveMethodInvocation.java:157) =09at org.apache.cloudstack.network.contrail.management.EventUtils$Even= tInterceptor.invoke(EventUtils.java:107) =09at org.springframework.aop.framework.ReflectiveMethodInvocation.proc= eed(ReflectiveMethodInvocation.java:168) =09at com.cloud.event.ActionEventInterceptor.invoke(ActionEventIntercep= tor.java:51) =09at org.springframework.aop.framework.ReflectiveMethodInvocation.proc= eed(ReflectiveMethodInvocation.java:168) =09at org.springframework.aop.interceptor.ExposeInvocationInterceptor.i= nvoke(ExposeInvocationInterceptor.java:92) =09at org.springframework.aop.framework.ReflectiveMethodInvocation.proc= eed(ReflectiveMethodInvocation.java:179) =09at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDy= namicAopProxy.java:213) =09at com.sun.proxy.$Proxy205.startVirtualMachine(Unknown Source) =09at org.apache.cloudstack.api.command.admin.vm.DeployVMCmdByAdmin.exe= cute(DeployVMCmdByAdmin.java:50) =09at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:150) =09at com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.= java:108) =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.r= unInContext(AsyncJobManagerImpl.java:554) =09at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.ru= n(ManagedContextRunnable.java:49) =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$= 1.call(DefaultManagedContext.java:56) =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.= callWithContext(DefaultManagedContext.java:103) =09at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.= runWithContext(DefaultManagedContext.java:53) =09at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(= ManagedContextRunnable.java:46) =09at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.r= un(AsyncJobManagerImpl.java:502) =09at java.util.concurrent.Executors$RunnableAdapter.call(Executors.jav= a:511) =09at java.util.concurrent.FutureTask.run(FutureTask.java:266) =09at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecu= tor.java:1142) =09at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExec= utor.java:617) =09at java.lang.Thread.run(Thread.java:745) Caused by: com.cloud.exception.InsufficientServerCapacityException: Una= ble to create a deployment for VM[User|i-4-3-VM]Scope=3Dinterface com.cloud= .dc.DataCenter; id=3D1 =09at com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMa= chineManagerImpl.java:961) =09at com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMa= chineManagerImpl.java:4661) =09at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) =09... 18 more 2017-03-04 20:43:41,182 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Jo= b-Executor-23:ctx-0c7ca79a job-29) (logid:b0dca526) Complete async job-29, = jobStatus: FAILED, resultCode: 530, result: org.apache.cloudstack.api.respo= nse.ExceptionResponse/null/{"uuidList":[],"errorcode":530,"errortext":"Unab= le to start a VM due to insufficient capacity"} ``` > Root disk resize support for VMware and XenServer > ------------------------------------------------- > > Key: CLOUDSTACK-9604 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-960= 4 > Project: CloudStack > Issue Type: Improvement > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > Reporter: Priyank Parihar > Assignee: Priyank Parihar > Attachments: 1.png, 2.png, 3.png > > > Currently the root size of an instance is locked to that of the template.= This creates unnecessary template duplicates, prevents the creation of a m= arket place, wastes time and disk space and generally makes work more compl= icated. > Real life example - a small VPS provider might want to offer the followin= g sizes (in GB): > 10,20,40,80,160,240,320,480,620 > That's 9 offerings. > The template selection could look like this, including real disk space us= ed: > Windows 2008 ~10GB > Windows 2008+Plesk ~15GB > Windows 2008+MSSQL ~15GB > Windows 2012 ~10GB > Windows 2012+Plesk ~15GB > Windows 2012+MSSQL ~15GB > CentOS ~1GB > CentOS+CPanel ~3GB > CentOS+Virtualmin ~3GB > CentOS+Zimbra ~3GB > CentOS+Docker ~2GB > Debian ~1GB > Ubuntu LTS ~1GB > In this case the total disk space used by templates will be 828 GB, that'= s almost 1 TB. If your storage is expensive and limited SSD this can get pa= inful! > If the root resize feature is enabled we can reduce this to under 100 GB. > Specifications and Description=20 > Administrators don't want to deploy duplicate OS templates of differi= ng sizes just to support different storage packages. Instead, the VM deploy= ment can accept a size for the root disk and adjust the template clone acco= rdingly. In addition, CloudStack already supports data disk resizing for ex= isting volumes, we can extend that functionality to resize existing root di= sks.=20 > As mentioned, we can leverage the existing design for resizing an exist= ing volume. The difference with root volumes is that we can't resize via di= sk offering, therefore we need to verify that no disk offering was passed, = just a size. The existing enforcements of new size > existing size will sti= ll server their purpose. > For deployment-based resize (ROOT volume size different from template = size), we pass the rootdisksize parameter when the existing code allocates = the root volume. In the process, we validate that the root disk size is > e= xisting template size, and non-zero. This will persist the root volume as t= he desired size regardless of whether or not the VM is started on deploy. T= hen hypervisor specific code needs to be made to pay attention to the Volum= eObjectTO's size attribute and use that when doing the work of cloning from= template, rather than inheriting the template's size. This can be implemen= ted one hypervisor at a time, and as such there needs to be a check in User= VmManagerImpl to fail unsupported hypervisors with InvalidParameterValueExc= eption when the rootdisksize is passed. > =20 > Hypervisor specific changes > XenServer > Resize ROOT volume is only supported for stopped VMs > Newly created ROOT volume will be resized after clone from template > VMware =20 > Resize ROOT volume is only supported for stopped VMs. > New size should be large then the previous size. > Newly created ROOT volume will be resized after clone from template iff > There is no root disk chaining.(means use Full clone) > And Root Disk controller setting is not IDE. > Previously created Root Volume could be resized iif > There is no root disk chaining. > And Root Disk controller setting is not IDE. > Web Services APIs > resizeVolume API call will not change, but it will accept volume UUIDs of= root volumes in id parameter for resizing. > deployVirtualMachine API call will allow new rootdisksize parameter to be= passed. This parameter will be used as the disk size (in GB) when cloning = from template. > UI > 1) (refer attached image 1) shows UI that resize volume option is added f= or ROOT disks. > 2) (refer attached image 2) when user calls the resize volume on ROOT vol= ume. Here only size option is shown. For DATADISK disk offerings are shown. > 3) (refer attached image 3) when user deploys VM. New option for Root dis= k size is added. -- This message was sent by Atlassian JIRA (v6.3.15#6346)