sadhu suresh created CLOUDSTACK-4549:
----------------------------------------
Summary: 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
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 is automatically generated by JIRA.
If you think it was sent incorrectly, please contact your JIRA administrators
For more information on JIRA, see: http://www.atlassian.com/software/jira
