cloudstack-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Abhinandan Prateek (JIRA)" <j...@apache.org>
Subject [jira] [Updated] (CLOUDSTACK-4549) ceph:deployvm from template created from snapshot is failing
Date Fri, 11 Oct 2013 06:44:43 GMT

     [ https://issues.apache.org/jira/browse/CLOUDSTACK-4549?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]

Abhinandan Prateek updated CLOUDSTACK-4549:
-------------------------------------------

    Assignee:     (was: Kishan Kavala)

> 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#6144)

Mime
View raw message