You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@cloudstack.apache.org by "Animesh Chaturvedi (JIRA)" <ji...@apache.org> on 2013/12/14 01:31:07 UTC
[jira] [Commented] (CLOUDSTACK-4549) ceph:deployvm from template
created from snapshot is failing
[ https://issues.apache.org/jira/browse/CLOUDSTACK-4549?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13848102#comment-13848102 ]
Animesh Chaturvedi commented on CLOUDSTACK-4549:
------------------------------------------------
Is anyone willing to look at this older issue from 4.2? Wido?
> ceph:deployvm from template created from snapshot is failing
> ------------------------------------------------------------
>
> Key: CLOUDSTACK-4549
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-4549
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the default.)
> Components: Management Server
> Affects Versions: 4.2.0
> Reporter: sadhu suresh
> Priority: Critical
> Fix For: 4.3.0
>
> Attachments: management-server.rar
>
>
> VM deployment form template which is created form snapshot is failing where as VM deployment with uploaded template successful.
> created template form snapshot is storing in raw format(https://10-147-49-100.realhostip.com/userdata/0c826645-ec82-40f1-a1b0-38fc9ba1c76f.raw).. not sure are we converting again to QCwo2
> when we try to deploy a VM
> steps:
> 1.create a ceph instance(configure compute offering with RBD and deploy a vm)
> 2.Once it successful,select the root partition and perform snapshot
> 3.once snapshot successful,create a template from snapshot
> 4 try to create vm uning above VM
> actual result:
> deploy vm failing with exception
> -29 15:21:43,756 DEBUG [cloud.network.NetworkModelImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Service SecurityGroup is not supported in the network id=204
> 2013-08-29 15:21:43,762 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Applying userdata and password entry in network Ntwk[204|Guest|8]
> 2013-08-29 15:21:43,800 DEBUG [agent.transport.Request] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Seq 1-2089159931: Sending { Cmd , MgmtId: 7175246184473, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.routing.SavePasswordCommand":{"password":"fnirq_cnffjbeq","vmIpAddress":"10.1.1.140","vmName":"6e798618-8e4d-4305-94a1-642e5f3aad08","executeInSequence":true,"accessDetails":{"router.guest.ip":"10.1.1.1","zone.network.type":"Advanced","router.ip":"169.254.2.248","router.name":"r-15-VM"},"wait":0}},{"com.cloud.agent.api.routing.VmDataCommand":{"vmIpAddress":"10.1.1.140","vmName":"6e798618-8e4d-4305-94a1-642e5f3aad08","executeInSequence":true,"accessDetails":{"router.guest.ip":"10.1.1.1","zone.network.type":"Advanced","router.ip":"169.254.2.248","router.name":"r-15-VM"},"wait":0}}] }
> 2013-08-29 15:21:44,168 DEBUG [agent.transport.Request] (AgentManager-Handler-14:null) Seq 1-2089159931: Processing: { Ans: , MgmtId: 7175246184473, via: 1, Ver: v1, Flags: 110, [{"com.cloud.agent.api.Answer":{"result":true,"wait":0}},{"com.cloud.agent.api.Answer":{"result":true,"wait":0}}] }
> 2013-08-29 15:21:44,169 DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-14:null) Seq 1-2089159931: No more commands found
> 2013-08-29 15:21:44,169 DEBUG [agent.transport.Request] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Seq 1-2089159931: Received: { Ans: , MgmtId: 7175246184473, via: 1, Ver: v1, Flags: 110, { Answer, Answer } }
> 2013-08-29 15:21:44,179 DEBUG [cloud.network.NetworkModelImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Service SecurityGroup is not supported in the network id=204
> 2013-08-29 15:21:44,182 DEBUG [cloud.storage.VolumeManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Checking if we need to prepare 1 volumes for VM[User|6e798618-8e4d-4305-94a1-642e5f3aad08]
> 2013-08-29 15:21:44,207 DEBUG [storage.image.TemplateDataFactoryImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) template 202 is already in store:1, type:Image
> 2013-08-29 15:21:44,219 DEBUG [storage.datastore.PrimaryDataStoreImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Not found (templateId:202poolId:5) in template_spool_ref, persisting it
> 2013-08-29 15:21:44,234 DEBUG [storage.image.TemplateDataFactoryImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) template 202 is already in store:5, type:Primary
> 2013-08-29 15:21:44,237 DEBUG [storage.volume.VolumeServiceImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Found template 246c1e4ac-9fc0-3122-bcfb-77deff789f61 in storage pool 5 with VMTemplateStoragePool id: 5
> 2013-08-29 15:21:44,433 DEBUG [storage.volume.VolumeServiceImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Acquire lock on VMTemplateStoragePool 5 with timeout 3600 seconds
> 2013-08-29 15:21:44,438 INFO [storage.volume.VolumeServiceImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) lock is acquired for VMTemplateStoragePool 5
> 2013-08-29 15:21:44,466 DEBUG [storage.motion.AncientDataMotionStrategy] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) copyAsync inspecting src type TEMPLATE copyAsync inspecting dest type TEMPLATE
> 2013-08-29 15:21:44,547 DEBUG [agent.transport.Request] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Seq 1-2089159932: Sending { Cmd , MgmtId: 7175246184473, via: 1, Ver: v1, Flags: 100111, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/2/202/54476705-667d-463d-b82b-a99aea739520.raw","uuid":"82d671d6-30ee-40d4-8ad1-f922bf055630","id":202,"format":"RAW","accountId":2,"hvm":true,"displayText":"tme","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://10.147.28.7/export/home/sadhu/asf/kvmsec","_role":"Image"}},"name":"246c1e4ac-9fc0-3122-bcfb-77deff789f61","hypervisorType":"KVM"}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"uuid":"82d671d6-30ee-40d4-8ad1-f922bf055630","id":202,"format":"RAW","accountId":2,"hvm":true,"displayText":"tme","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"9e422b10-88ae-3a56-96b3-6a7962c175cf","id":5,"poolType":"RBD","host":"10.147.41.3","path":"cloudstack2","port":6789}},"name":"246c1e4ac-9fc0-3122-bcfb-77deff789f61","hypervisorType":"KVM"}},"executeInSequence":true,"wait":10800}}] }
> 2013-08-29 15:21:44,801 DEBUG [agent.transport.Request] (AgentManager-Handler-1:null) Seq 1-2089159932: Processing: { Ans: , MgmtId: 7175246184473, via: 1, Ver: v1, Flags: 110, [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"com.cloud.utils.exception.CloudRuntimeException: Failed to copy /mnt/81536f26-0b44-391e-a9b2-03c89eec2957/54476705-667d-463d-b82b-a99aea739520.raw to 634e63f2-c2fe-447d-bfab-c2b86a839589","wait":0}}] }
> 2013-08-29 15:21:44,801 DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-1:null) Seq 1-2089159932: No more commands found
> 2013-08-29 15:21:44,802 DEBUG [agent.transport.Request] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Seq 1-2089159932: Received: { Ans: , MgmtId: 7175246184473, via: 1, Ver: v1, Flags: 110, { CopyCmdAnswer } }
> 2013-08-29 15:21:44,816 INFO [storage.volume.VolumeServiceImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) releasing lock for VMTemplateStoragePool 5
> 2013-08-29 15:21:44,817 WARN [utils.db.Merovingian2] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Was unable to find lock for the key template_spool_ref5 and thread id 1321874944
> 2013-08-29 15:21:44,818 DEBUG [cloud.storage.VolumeManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Unable to create Vol[19|vm=18|ROOT]:com.cloud.utils.exception.CloudRuntimeException: Failed to copy /mnt/81536f26-0b44-391e-a9b2-03c89eec2957/54476705-667d-463d-b82b-a99aea739520.raw to 634e63f2-c2fe-447d-bfab-c2b86a839589
> 2013-08-29 15:21:44,821 INFO [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Unable to contact resource.
> com.cloud.exception.StorageUnavailableException: Resource [StoragePool:5] is unreachable: Unable to create Vol[19|vm=18|ROOT]:com.cloud.utils.exception.CloudRuntimeException: Failed to copy /mnt/81536f26-0b44-391e-a9b2-03c89eec2957/54476705-667d-463d-b82b-a99aea739520.raw to 634e63f2-c2fe-447d-bfab-c2b86a839589
> at com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2534)
> at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2582)
> at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:888)
> at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:578)
> at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:227)
> at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
> at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3406)
> at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2966)
> at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2952)
> at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
> at org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:420)
> at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
> at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531)
> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
> at java.util.concurrent.FutureTask.run(FutureTask.java:166)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> at java.lang.Thread.run(Thread.java:679)
> 2013-08-29 15:21:44,849 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Cleaning up resources for the vm VM[User|6e798618-8e4d-4305-94a1-642e5f3aad08] in Starting state
> 2013-08-29 15:21:44,852 DEBUG [agent.transport.Request] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Seq 1-2089159933: Sending { Cmd , MgmtId: 7175246184473, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"i-2-18-VM","wait":0}}] }
> 2013-08-29 15:21:44,973 DEBUG [agent.transport.Request] (AgentManager-Handler-2:null) Seq 1-2089159933: Processing: { Ans: , MgmtId: 7175246184473, via: 1, Ver: v1, Flags: 110, [{"com.cloud.agent.api.StopAnswer":{"vncPort":0,"result":true,"wait":0}}] }
> 2013-08-29 15:21:44,974 DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-2:null) Seq 1-2089159933: No more commands found
> 2013-08-29 15:21:44,974 DEBUG [agent.transport.Request] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Seq 1-2089159933: Received: { Ans: , MgmtId: 7175246184473, via: 1, Ver: v1, Flags: 110, { StopAnswer } }
> 2013-08-29 15:21:44,996 DEBUG [cloud.network.NetworkModelImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Service SecurityGroup is not supported in the network id=204
> 2013-08-29 15:21:45,006 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Changing active number of nics for network id=204 on -1
> 2013-08-29 15:21:45,020 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Asking VirtualRouter to release Nic[39-18-8598ce9e-e36d-4fe7-8e5b-da21f62eae49-10.1.1.140]
> 2013-08-29 15:21:45,020 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Successfully released network resources for the vm VM[User|6e798618-8e4d-4305-94a1-642e5f3aad08]
> 2013-08-29 15:21:45,022 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Successfully cleanued up resources for the vm VM[User|6e798618-8e4d-4305-94a1-642e5f3aad08] in Starting state
> 2013-08-29 15:21:45,031 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Deploy avoids pods: [], clusters: [], hosts: [1]
> 2013-08-29 15:21:45,032 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_86625c40@e625416
> 2013-08-29 15:21:45,032 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested cpu: 1, requested ram: 104857600
> 2013-08-29 15:21:45,032 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Is ROOT volume READY (pool already allocated)?: No
> 2013-08-29 15:21:45,033 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) DeploymentPlan has host_id specified, choosing this host and making no checks on this host: 1
> 2013-08-29 15:21:45,034 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) The specified host is in avoid set
> 2013-08-29 15:21:45,034 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Cannnot deploy to specified host, returning.
> 2013-08-29 15:21:45,047 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) VM state transitted from :Starting to Stopped with event: OperationFailedvm's original host id: null new host id: null host id before state transition: 1
> 2013-08-29 15:21:45,054 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Hosts's actual total CPU: 9572 and CPU after applying overprovisioning: 9572
> 2013-08-29 15:21:45,054 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Hosts's actual total RAM: 16816889856 and RAM after applying overprovisioning: 16816889856
> 2013-08-29 15:21:45,054 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) release cpu from host: 1, old used: 4503,reserved: 0, actual total: 9572, total with overprovisioning: 9572; new used: 4502,reserved:0; movedfromreserved: false,moveToReserveredfalse
> 2013-08-29 15:21:45,054 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) release mem from host: 1, old used: 3401580544,reserved: 0, total: 16816889856; new used: 3296722944,reserved:0; movedfromreserved: false,moveToReserveredfalse
> 2013-08-29 15:21:45,109 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) VM state transitted from :Stopped to Starting with event: StartRequestedvm's original host id: null new host id: null host id before state transition: null
> 2013-08-29 15:21:45,111 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Successfully transitioned to start state for VM[User|6e798618-8e4d-4305-94a1-642e5f3aad08] reservation id = 8a4c528a-3292-4ccf-9790-eaa703f23452
> 2013-08-29 15:21:45,128 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Trying to deploy VM, vm has dcId: 1 and podId: 1
> 2013-08-29 15:21:45,129 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Deploy avoids pods: [], clusters: [], hosts: [1]
> 2013-08-29 15:21:45,156 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Deploy avoids pods: [], clusters: [], hosts: [1]
> 2013-08-29 15:21:45,160 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_86625c40@e625416
> 2013-08-29 15:21:45,160 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:null, requested cpu: 1, requested ram: 104857600
> 2013-08-29 15:21:45,161 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Is ROOT volume READY (pool already allocated)?: No
> 2013-08-29 15:21:45,161 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Searching resources only under specified Pod: 1
> 2013-08-29 15:21:45,162 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Pod: 1
> 2013-08-29 15:21:45,172 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Removing from the clusterId list these clusters from avoid set: []
> 2013-08-29 15:21:45,284 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Checking resources in Cluster: 1 under Pod: 1
> 2013-08-29 15:21:45,284 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ] FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1
> 2013-08-29 15:21:45,291 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ] FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]]
> 2013-08-29 15:21:45,393 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ] FirstFitRoutingAllocator) Found 1 hosts for allocation after prioritization: [Host[-1-Routing]]
> 2013-08-29 15:21:45,394 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ] FirstFitRoutingAllocator) Looking for speed=1Mhz, Ram=100
> 2013-08-29 15:21:45,394 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ] FirstFitRoutingAllocator) Host name: Rack1Pod1Host12, hostId: 1 is in avoid set, skipping this and trying other available hosts
> 2013-08-29 15:21:45,394 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ] FirstFitRoutingAllocator) Host Allocator returning 0 suitable hosts
> 2013-08-29 15:21:45,394 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) No suitable hosts found
> 2013-08-29 15:21:45,394 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) No suitable hosts found under this Cluster: 1
> 2013-08-29 15:21:45,398 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Could not find suitable Deployment Destination for this VM under any clusters, returning.
> 2013-08-29 15:21:45,398 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Searching resources only under specified Pod: 1
> 2013-08-29 15:21:45,398 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Pod: 1
> 2013-08-29 15:21:45,405 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Removing from the clusterId list these clusters from avoid set: [1]
> 2013-08-29 15:21:45,406 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) No clusters found after removing disabled clusters and clusters in avoid list, returning.
> 2013-08-29 15:21:45,463 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) VM state transitted from :Starting to Stopped with event: OperationFailedvm's original host id: null new host id: null host id before state transition: null
> 2013-08-29 15:21:45,471 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===START=== 10.252.192.42 -- GET command=queryAsyncJobResult&jobId=3dcbbb22-d258-4859-bf3f-c8a620768e25&response=json&sessionkey=iYZwRL0CmiLGtiKuOVqbMz9cafo%3D&_=1377750462217
> 2013-08-29 15:21:45,525 DEBUG [cloud.vm.UserVmManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) Destroying vm VM[User|6e798618-8e4d-4305-94a1-642e5f3aad08] as it failed to create on Host with Id:null
> 2013-08-29 15:21:46,360 DEBUG [cloud.api.ApiServlet] (catalina-exec-3:null) ===END=== 10.252.192.42 -- GET command=queryAsyncJobResult&jobId=3dcbbb22-d258-4859-bf3f-c8a620768e25&response=json&sessionkey=iYZwRL0CmiLGtiKuOVqbMz9cafo%3D&_=1377750462217
> 2013-08-29 15:21:46,372 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) 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
> 2013-08-29 15:21:46,411 WARN [apache.cloudstack.alerts] (Job-Executor-14:job-42 = [ 3dcbbb22-d258-4859-bf3f-c8a620768e25 ]) alertType:: 8 // dataCenterId:: 1 // podId:: 1 // clusterId:: null // message:: Failed to deploy Vm with Id: 18, on Host with Id: null
> ^C
> id: 41
> user_id: 2
> account_id: 2
> session_key: NULL
> instance_type: NULL
> instance_id: NULL
> job_cmd: org.apache.cloudstack.api.command.user.vm.DeployVMCmd
> job_cmd_originator: NULL
> job_cmd_info: {"sessionkey":"iYZwRL0CmiLGtiKuOVqbMz9cafo\u003d","cmdEventType":"VM.CREATE","ctxUserId":"2","serviceOfferingId":"8f42daa3-37aa-48d2-b01d-903cfc19345b","httpmethod":"GET","zoneId":"3cc53a2d-6e2b-4ccb-8ccb-6fe2e7d9eb54","templateId":"d3b4b386-0fda-11e3-9982-06869e000019","response":"json","id":"17","networkIds":"05fea399-b39d-4428-85db-c09eb7815f94","hypervisor":"KVM","_":"1377750411786","ctxAccountId":"2","ctxStartEventId":"167"}
> job_cmd_ver: 0
> callback_type: 0
> callback_address: NULL
> job_status: 1
> job_process_status: 0
> job_result_code: 0
> job_result: org.apache.cloudstack.api.response.UserVmResponse/virtualmachine/{"id":"3c88b4ed-3da0-4808-a7f5-216478884303","name":"3c88b4ed-3da0-4808-a7f5-216478884303","account":"admin","domainid":"d3ac65c8-0fda-11e3-9982-06869e000019","domain":"ROOT","created":"2013-08-29T15:20:56+0530","state":"Running","haenable":false,"zoneid":"3cc53a2d-6e2b-4ccb-8ccb-6fe2e7d9eb54","zonename":"zone1","hostid":"8c647661-3a79-4da5-bc6a-b15d57193ca5","hostname":"Rack1Pod1Host12","templateid":"d3b4b386-0fda-11e3-9982-06869e000019","templatename":"CentOS 5.5(64-bit) no GUI (KVM)","templatedisplaytext":"CentOS 5.5(64-bit) no GUI (KVM)","passwordenabled":false,"serviceofferingid":"8f42daa3-37aa-48d2-b01d-903cfc19345b","serviceofferingname":"rbdcp","cpunumber":1,"cpuspeed":1,"memory":100,"guestosid":"d3f8475e-0fda-11e3-9982-06869e000019","rootdeviceid":0,"rootdevicetype":"ROOT","securitygroup":[],"nic":[{"id":"e8af7e43-be9b-4205-aed4-4746d1e3c65f","networkid":"05fea399-b39d-4428-85db-c09eb7815f94","networkname":"test","netmask":"255.255.255.0","gateway":"10.1.1.1","ipaddress":"10.1.1.176","isolationuri":"vlan://1012","broadcasturi":"vlan://1012","traffictype":"Guest","type":"Isolated","isdefault":true,"macaddress":"02:00:33:31:00:05"}],"hypervisor":"KVM","instancename":"i-2-17-VM","tags":[],"affinitygroup":[],"displayvm":true,"isdynamicallyscalable":false,"jobid":"10d88595-3191-4dff-a04c-38a5cac47a07","jobstatus":0}
> job_init_msid: 7175246184473
> job_complete_msid: 7175246184473
> created: 2013-08-29 09:50:56
> last_updated: 2013-08-29 09:51:02
> last_polled: 2013-08-29 09:51:02
> removed: NULL
> uuid: 10d88595-3191-4dff-a04c-38a5cac47a07
> *************************** 42. row ***************************
> id: 42
> user_id: 2
> account_id: 2
> session_key: NULL
> instance_type: NULL
> instance_id: NULL
> job_cmd: org.apache.cloudstack.api.command.user.vm.DeployVMCmd
> job_cmd_originator: NULL
> job_cmd_info: {"sessionkey":"iYZwRL0CmiLGtiKuOVqbMz9cafo\u003d","cmdEventType":"VM.CREATE","ctxUserId":"2","serviceOfferingId":"8f42daa3-37aa-48d2-b01d-903cfc19345b","httpmethod":"GET","zoneId":"3cc53a2d-6e2b-4ccb-8ccb-6fe2e7d9eb54","templateId":"82d671d6-30ee-40d4-8ad1-f922bf055630","response":"json","id":"18","networkIds":"05fea399-b39d-4428-85db-c09eb7815f94","hypervisor":"KVM","_":"1377750452631","ctxAccountId":"2","ctxStartEventId":"171"}
> job_cmd_ver: 0
> callback_type: 0
> callback_address: NULL
> job_status: 2
> job_process_status: 0
> job_result_code: 530
> job_result: org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":533,"errortext":"Unable to create a deployment for VM[User|6e798618-8e4d-4305-94a1-642e5f3aad08]"}
> job_init_msid: 7175246184473
> job_complete_msid: 7175246184473
> created: 2013-08-29 09:51:39
> last_updated: 2013-08-29 09:51:47
> last_polled: 2013-08-29 09:51:45
> removed: NULL
> uuid: 3dcbbb22-d258-4859-bf3f-c8a620768e25
> 42 rows in set (0.00 sec)
> ERROR:
> oot@Rack1Pod1Host21:~# ceph osd tree
> # id weight type name up/down reweight
> -1 0.14 root default
> -2 0.14 host ubuntu3
> 0 0.06999 osd.0 up 1
> 1 0.06999 osd.1 up 1
--
This message was sent by Atlassian JIRA
(v6.1.4#6159)