Return-Path: X-Original-To: apmail-cloudstack-issues-archive@www.apache.org Delivered-To: apmail-cloudstack-issues-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 8CA7C100CB for ; Wed, 27 Nov 2013 11:15:39 +0000 (UTC) Received: (qmail 90700 invoked by uid 500); 27 Nov 2013 11:15:38 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 90560 invoked by uid 500); 27 Nov 2013 11:15:38 -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 90321 invoked by uid 500); 27 Nov 2013 11:15:37 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 90306 invoked by uid 99); 27 Nov 2013 11:15:37 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Wed, 27 Nov 2013 11:15:37 +0000 Date: Wed, 27 Nov 2013 11:15:36 +0000 (UTC) From: "Jayapal Reddy (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (CLOUDSTACK-4593) [VMWARE] [Upgrade]Livestorage Migration & VM Snapshot features are not fully functional after upgrade MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ https://issues.apache.org/jira/browse/CLOUDSTACK-4593?page=3Dcom.atla= ssian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId= =3D13833663#comment-13833663 ]=20 Jayapal Reddy commented on CLOUDSTACK-4593: ------------------------------------------- Assigning to RamG for reassigning. > [VMWARE] [Upgrade]Livestorage Migration & VM Snapshot features are not f= ully functional after upgrade > -------------------------------------------------------------------------= ----------------------------- > > Key: CLOUDSTACK-4593 > URL: https://issues.apache.org/jira/browse/CLOUDSTACK-459= 3 > Project: CloudStack > Issue Type: Bug > Security Level: Public(Anyone can view this level - this is the defa= ult.)=20 > Components: Storage Controller, Upgrade, VMware > Affects Versions: 4.2.1 > Reporter: Sailaja Mada > Assignee: Ram Ganesh > Fix For: 4.3.0 > > Attachments: apilogs.rar, clouddbback.dmp, mgmtlogs.rar > > > Steps: > Upgraded setup : > 307 Setup with 2 clusters ( Cluster1 =E2=80=93 2 ESXi 5.0 hosts , Cluster= 2 =E2=80=93 Esxi 4.1 host)=20 > 1.=0911 Vm=E2=80=99s deployed with 2 VM=E2=80=99s in stopped state=20 > 2.=09VM=E2=80=99s with ROOT volume, 2 DATA volumes, 3 DATA volumes=20 > 3.=09Snapshots , Template / volumes from this snapshot=20 > 4.=09Detached volumes=20 > 5.=09Empty volumes which are not attached to any instance=20 > 6.=09Cluster with 2 primary storage's=20 > Upgraded to 4.2 =20 > I got into below failures with VM Snapshot if tried without Stop/Start of= the VM Post upgrade : > 2013-09-02 21:35:36,733 WARN [vmware.resource.VmwareResource] (DirectAge= nt-320:10.102.192.23) StartCommand failed due to Exception: java.lang.Runti= meException > Message: File was not found > java.lang.RuntimeException: File was not found > at com.cloud.hypervisor.vmware.util.VmwareClient.waitForTask(Vmwa= reClient.java:378) > at com.cloud.hypervisor.vmware.mo.VirtualMachineMO.powerOn(Virtua= lMachineMO.java:188) > at com.cloud.hypervisor.vmware.resource.VmwareResource.execute(Vm= wareResource.java:2966) > at com.cloud.hypervisor.vmware.resource.VmwareResource.executeReq= uest(VmwareResource.java:519) > at com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgen= tAttache.java:186) > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.= java:471) > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:= 334) > at java.util.concurrent.FutureTask.run(FutureTask.java:166) > at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.access$101(ScheduledThreadPoolExecutor.java:165) > at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutu= reTask.run(ScheduledThreadPoolExecutor.java:266) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolEx= ecutor.java:1110) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.java:603) > at java.lang.Thread.run(Thread.java:679) > 2013-09-02 21:35:36,742 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-320:null) Seq 6-949618439: Response Received: > 2013-09-02 21:35:43,972 INFO [storage.volume.VolumeServiceImpl] (Job-Exe= cutor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Volume 52 is= not referred anywhere, remove it from volumes table > 2013-09-02 21:35:43,981 ERROR [cloud.storage.VolumeManagerImpl] (Job-Exec= utor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) migrate volum= e failed:copy volume from primary to secondary failed due to exception: Exc= eption: java.lang.Exception > Message: Unable to find related disk device for volume. volume path: ROOT= -14-30-000004 > 2013-09-02 21:35:43,990 INFO [cloud.vm.VirtualMachineManagerImpl] (Job-E= xecutor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Unable to = contact resource. > com.cloud.exception.StorageUnavailableException: Resource [StoragePool:20= 3] is unreachable: migrate volume failed: copy volume from primary to secon= dary failed due to exception: Exception: java.lang.Exception > Message: Unable to find related disk device for volume. volume path: ROOT= -14-30-000004 > at com.cloud.storage.VolumeManagerImpl.migrateVolume(VolumeManage= rImpl.java:2254) > at com.cloud.utils.component.ComponentInstantiationPostProcessor$= InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:12= 5) > at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.= java:2590) > at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMac= hineManagerImpl.java:888) > at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineMan= agerImpl.java:578) > at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerI= mpl.deployVirtualMachine(VMEntityManagerImpl.java:237) > at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEn= tityImpl.deploy(VirtualMachineEntityImpl.java:209) > at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManag= erImpl.java:3406) > at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManag= erImpl.java:1948) > at com.cloud.utils.component.ComponentInstantiationPostProcessor$= InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:12= 5) > at org.apache.cloudstack.api.command.user.vm.StartVMCmd.execute(S= tartVMCmd.java:120) > at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158) > at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.= java:531) > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.= java:471) > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:= 334) > at java.util.concurrent.FutureTask.run(FutureTask.java:166) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolEx= ecutor.java:1110) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.java:603) > at java.lang.Thread.run(Thread.java:679) > 2013-09-02 21:35:44,008 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-E= xecutor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Cleaning u= p resources for the vm VM[User|instance6withdefaulttempalte] in Starting st= ate > 2013-09-02 21:35:44,009 DEBUG [agent.transport.Request] (Job-Executor-75:= job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Seq 8-343475044: Send= ing { Cmd , MgmtId: 227594284004867, via: 8, Ver: v1, Flags: 100111, [{"co= m.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"v= mName":"i-3-14-VM","wait":0}}] } > 2013-09-02 21:35:44,009 DEBUG [agent.transport.Request] (Job-Executor-75:= job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Seq 8-343475044: Exec= uting: { Cmd , MgmtId: 227594284004867, via: 8, Ver: v1, Flags: 100111, [{= "com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true= ,"vmName":"i-3-14-VM","wait":0}}] } > 2013-09-02 21:35:44,010 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-106:null) Seq 8-343475044: Executing request > 2013-09-02 21:35:44,010 INFO [vmware.resource.VmwareResource] (DirectAge= nt-106:10.102.192.14) Executing resource StopCommand: {"isProxy":false,"exe= cuteInSequence":true,"vmName":"i-3-14-VM","wait":0} > But finally VM got started moving to other host in the cluser: Note : The= re is no gurantee that this feature works fine without restart of the VM . = After this chain info got updated (chain_info: {"diskDeviceBusName":"ide0:1= ","diskChain":["[61105200a56c3a5f9c83783d3d36a9d1] i-3-14-VM/436fd32b103746= d29771a2b10df35cf2.vmdk"]}) > 2013-09-02 21:35:44,165 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl= ] (Job-Executor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Tr= ying to find a potenial host and associated storage pools from the suitable= host/pool lists for this VM > 2013-09-02 21:35:44,165 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl= ] (Job-Executor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Ch= ecking if host: 1 can access any suitable storage pool for volume: ROOT > 2013-09-02 21:35:44,166 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl= ] (Job-Executor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Ho= st: 1 can access pool: 201 > 2013-09-02 21:35:44,168 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl= ] (Job-Executor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Fo= und a potential host id: 1 name: 10.102.192.20 and associated storage pools= for this VM > 2013-09-02 21:35:44,169 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl= ] (Job-Executor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Re= turning Deployment Destination: Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-= Storage(Volume(Id|Type-->Pool(Id))] : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1= )-Storage(Volume(30|ROOT-->Pool(201))] > 2013-09-02 21:35:44,169 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-E= xecutor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) Deployment= found - P0=3DVM[User|instance6withdefaulttempalte], P0=3DDest[Zone(Id)-Po= d(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))] : Dest[Zone(= 1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(30|ROOT-->Pool(201))] > 2013-09-02 21:35:44,176 DEBUG [cloud.server.StatsCollector] (StatsCollect= or-3:null) HostStatsCollector is running... > 2013-09-02 21:35:44,191 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-E= xecutor-75:job-151 =3D [ 3e8e2327-7c56-45ea-a653-ee5b89d75d57 ]) VM state t= ransitted from :Starting to Starting with event: OperationRetryvm's origina= l host id: 6 new host id: 1 host id before state transition: 8 > Second Scenario: > 1. Create a VM in 307=20 > 2. Do not perform any stop and start after upgrade to 4.2=20 > 3. Live storage migrate to second cluster , then take a volume snapshot > 4. Create the template from this volume snapshot after live storage migra= tion=20 > 5. Try deployment of the VM using this template. Deployment works and V= M is up & running( I could login and create files also) . > 6. But during deployment there were errors while creating the Volume. La= ter it finds one more storage pool / host and creates volume on this new st= orage pool. Finally deploy VM works fine, > ERROR:=20 > 2013-09-02 23:51:11,130 INFO [storage.volume.VolumeServiceImpl] (Job-Exe= cutor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) releasing l= ock for VMTemplateStoragePool 19 > 2013-09-02 23:51:11,131 WARN [utils.db.Merovingian2] (Job-Executor-106:j= ob-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Was unable to find loc= k for the key template_spool_ref19 and thread id 1340523980 > 2013-09-02 23:51:11,131 DEBUG [cloud.storage.VolumeManagerImpl] (Job-Exec= utor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Unable to cr= eate Vol[59|vm=3D25|ROOT]:Unable to copy template to primary storage due to= exception:Exception: javax.xml.ws.soap.SOAPFaultException > Message: > Required parameter spec is missing > while parsing call information for method ImportVApp > at line 1, column 110 > while parsing SOAP body > at line 1, column 102 > while parsing SOAP envelope > at line 1, column 38 > while parsing HTTP request for method importVApp > on object of type vim.ResourcePool > at line 1, column 0 > 2013-09-02 23:51:11,131 INFO [cloud.vm.VirtualMachineManagerImpl] (Job-E= xecutor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Unable to= contact resource. > com.cloud.exception.StorageUnavailableException: Resource [StoragePool:20= 2] is unreachable: Unable to create Vol[59|vm=3D25|ROOT]:Unable to copy tem= plate to primary storage due to exception:Exception: javax.xml.ws.soap.SOAP= FaultException > Message: > Required parameter spec is missing > while parsing call information for method ImportVApp > at line 1, column 110 > while parsing SOAP body > at line 1, column 102 > while parsing SOAP envelope > at line 1, column 38 > while parsing HTTP request for method importVApp > on object of type vim.ResourcePool > at line 1, column 0 > at com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManag= erImpl.java:2544) > at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.= java:2592) > at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMac= hineManagerImpl.java:888) > at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineMan= agerImpl.java:578) > at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerI= mpl.deployVirtualMachine(VMEntityManagerImpl.java:227) > at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEn= tityImpl.deploy(VirtualMachineEntityImpl.java:209) > at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManag= erImpl.java:3406) > at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManag= erImpl.java:2966) > at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManag= erImpl.java:2952) > at com.cloud.utils.component.ComponentInstantiationPostProcessor$= InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:12= 5) > at org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVM= Cmd.java:420) > at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158) > at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.= java:531) > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.= java:471) > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:= 334) > at java.util.concurrent.FutureTask.run(FutureTask.java:166) > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolEx= ecutor.java:1110) > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolE= xecutor.java:603) > at java.lang.Thread.run(Thread.java:679) > 2013-09-02 23:51:11,139 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-E= xecutor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Cleaning = up resources for the vm VM[User|xxxx] in Starting state > 2013-09-02 23:51:11,141 DEBUG [agent.transport.Request] (Job-Executor-106= :job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Seq 6-949618915: Sen= ding { Cmd , MgmtId: 227594284004867, via: 6, Ver: v1, Flags: 100111, [{"c= om.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"= vmName":"i-3-25-VM","wait":0}}] } > 2013-09-02 23:51:11,141 DEBUG [agent.transport.Request] (Job-Executor-106= :job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Seq 6-949618915: Exe= cuting: { Cmd , MgmtId: 227594284004867, via: 6, Ver: v1, Flags: 100111, [= {"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":tru= e,"vmName":"i-3-25-VM","wait":0}}] } > 2013-09-02 23:51:11,141 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-91:null) Seq 6-949618915: Executing request > 2013-09-02 23:51:11,141 INFO [vmware.resource.VmwareResource] (DirectAge= nt-91:10.102.192.23) Executing resource StopCommand: {"isProxy":false,"exec= uteInSequence":true,"vmName":"i-3-25-VM","wait" > Deploy VM =E2=80=93 PASS: > 2013-09-02 23:51:29,848 DEBUG [agent.manager.DirectAgentAttache] (DirectA= gent-82:null) Seq 2-618137852: Response Received: > 2013-09-02 23:51:29,849 DEBUG [agent.transport.Request] (DirectAgent-82:n= ull) Seq 2-618137852: Processing: { Ans: , MgmtId: 227594284004867, via: 2= , Ver: v1, Flags: 110, [{"com.cloud.agent.api.StartAnswer":{"vm":{"id":25,"= name":"i-3-25-VM","bootloader":"HVM","type":"User","cpus":1,"minSpeed":500,= "maxSpeed":500,"minRam":536870912,"maxRam":536870912,"hostName":"xxxx","arc= h":"x86_64","os":"CentOS 5.3 (64-bit)","bootArgs":"","rebootOnCrash":false,= "enableHA":false,"limitCpuUse":false,"enableDynamicallyScaleVm":false,"vncP= assword":"95b29c111ca96aee","params":{"nicAdapter":"E1000","vmware.reserve.= cpu":"false","nestedVirtualizationFlag":"false","Message.ReservedCapacityFr= eed.Flag":"false","rootDiskController":"ide","vmware.reserve.mem":"false"},= "uuid":"021f2108-2c83-485a-ab6a-e3eab3fc585c","disks":[{"data":{"org.apache= .cloudstack.storage.to.VolumeObjectTO":{"uuid":"b14d9ecc-0474-4aa7-9b7d-3f8= 620381e64","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.= to.PrimaryDataStoreTO":{"uuid":"61105200-a56c-3a5f-9c83-783d3d36a9d1","id":= 201,"poolType":"NetworkFilesystem","host":"10.102.192.100","path":"/cpg_vol= /sailaja/workps2","port":2049}},"name":"ROOT-25","size":2147483648,"path":"= ROOT-25","volumeId":59,"vmName":"i-3-25-VM","accountId":3,"chainInfo":"{\"d= iskDeviceBusName\":\"ide0:1\",\"diskChain\":[\"[61105200a56c3a5f9c83783d3d3= 6a9d1] i-3-25-VM/ROOT-25.vmdk\",\"[61105200a56c3a5f9c83783d3d36a9d1] 6a9fa9= a8a4af3849a58167751abc214f/6a9fa9a8a4af3849a58167751abc214f.vmdk\"]}","form= at":"OVA","id":59,"hypervisorType":"VMware"}},"diskSeq":0,"type":"ROOT"},{"= data":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"id":0,"format"= :"ISO","accountId":0,"hvm":false}},"diskSeq":3,"type":"ISO"}],"nics":[{"dev= iceId":0,"networkRateMbps":200,"defaultNic":true,"uuid":"710aa816-6e4a-4f2e= -b876-3bb901e881e4","ip":"10.1.1.12","netmask":"255.255.255.0","gateway":"1= 0.1.1.1","mac":"02:00:4c:e2:00:0c","dns1":"10.103.128.15","broadcastType":"= Vlan","type":"Guest","broadcastUri":"vlan://786","isolationUri":"vlan://786= ","isSecurityGroupEnabled":false}]},"result":true,"wait":0}}] } > 2013-09-02 23:51:29,849 DEBUG [agent.transport.Request] (Job-Executor-106= :job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Seq 2-618137852: Rec= eived: { Ans: , MgmtId: 227594284004867, via: 2, Ver: v1, Flags: 110, { St= artAnswer } } > 2013-09-02 23:51:29,852 DEBUG [agent.manager.AgentAttache] (DirectAgent-8= 2:null) Seq 2-618137852: No more commands found > 2013-09-02 23:51:29,872 INFO [cloud.storage.VolumeManagerImpl] (Job-Exec= utor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Update volum= e disk chain info. vol: 59, ROOT-25 -> ROOT-25, null -> {"diskDeviceBusName= ":"ide0:1","diskChain":["[61105200a56c3a5f9c83783d3d36a9d1] i-3-25-VM/ROOT-= 25.vmdk","[61105200a56c3a5f9c83783d3d36a9d1] 6a9fa9a8a4af3849a58167751abc21= 4f/6a9fa9a8a4af3849a58167751abc214f.vmdk"]} > 2013-09-02 23:51:29,919 DEBUG [cloud.network.NetworkModelImpl] (Job-Execu= tor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Service Secur= ityGroup is not supported in the network id=3D204 > 2013-09-02 23:51:29,924 DEBUG [cloud.network.NetworkModelImpl] (Job-Execu= tor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Service Secur= ityGroup is not supported in the network id=3D204 > 2013-09-02 23:51:29,931 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-E= xecutor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) VM state = transitted from :Starting to Running with event: OperationSucceededvm's ori= ginal host id: 2 new host id: 2 host id before state transition: 2 > 2013-09-02 23:51:29,932 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-E= xecutor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Start com= pleted for VM VM[User|xxxx] > 2013-09-02 23:51:29,951 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Exec= utor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Complete asy= nc job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ], jobStatus: 1, resu= ltCode: 0, result: org.apache.cloudstack.api.response.UserVmResponse@3ac3be= ea > 2013-09-02 23:51:29,963 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Exec= utor-106:job-182 =3D [ ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ]) Done executi= ng org.apache.cloudstack.api.command.user.vm.DeployVMCmd for job-182 =3D [ = ed8268f7-bb81-4f02-a94b-3500d4f1d5cf ] -- This message was sent by Atlassian JIRA (v6.1#6144)