cloudstack-users-cn mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From 严思恒 <yansiheng...@163.com>
Subject Re:回复: CS4.2.1下系统虚拟机起不来,重复创建!!!!
Date Wed, 17 Sep 2014 07:30:12 GMT


hypervisor用的是xenserver6.2.0


最开始报的错误如下,但是用4.0.2+xenserver6.2.0就可以起来系统虚拟机,一切正常,支持xenserver6.2.0是后来加的!!


2014-09-17 15:08:29,672 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-20:null) Seq
1-1805189139: Executing request
2014-09-17 15:08:29,681 DEBUG [storage.volume.VolumeServiceImpl] (consoleproxy-1:null) Acquire
lock on VMTemplateStoragePool 4 with timeout 3600 seconds
2014-09-17 15:08:31,893 WARN  [xen.resource.XenServerStorageProcessor] (DirectAgent-20:null)
destoryVDIbyNameLabel failed due to there are 0 VDIs with name cloud-f2dce38e-3c5d-4dfb-afbb-5646433e7b22
2014-09-17 15:08:31,893 WARN  [xen.resource.XenServerStorageProcessor] (DirectAgent-20:null)
can not create vdi in sr 50515c54-a7f4-dd0c-a6f2-de8f8cb3edc4
2014-09-17 15:08:31,893 WARN  [xen.resource.XenServerStorageProcessor] (DirectAgent-20:null)
Catch Exception com.cloud.utils.exception.CloudRuntimeException for template +  due to com.cloud.utils.exception.CloudRuntimeException:
can not create vdi in sr 50515c54-a7f4-dd0c-a6f2-de8f8cb3edc4
com.cloud.utils.exception.CloudRuntimeException: can not create vdi in sr 50515c54-a7f4-dd0c-a6f2-de8f8cb3edc4
at com.cloud.hypervisor.xen.resource.XenServerStorageProcessor.copy_vhd_from_secondarystorage(XenServerStorageProcessor.java:793)
at com.cloud.hypervisor.xen.resource.XenServerStorageProcessor.copyTemplateToPrimaryStorage(XenServerStorageProcessor.java:864)
at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:70)
at com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:49)
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:625)
at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:59)
at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:106)
at com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:186)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:206)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
2014-09-17 15:08:31,895 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-20:null) Seq
1-1805189139: Response Received: 
2014-09-17 15:08:31,896 DEBUG [agent.transport.Request] (DirectAgent-20:null) Seq 1-1805189139:
Processing:  { Ans: , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 110, [{"org.apache.cloudstack.storage.command.CopyCmdAnswer":{"result":false,"details":"Catch
Exception com.cloud.utils.exception.CloudRuntimeException for template +  due to com.cloud.utils.exception.CloudRuntimeException:
can not create vdi in sr 50515c54-a7f4-dd0c-a6f2-de8f8cb3edc4","wait":0}}] }
2014-09-17 15:08:31,896 DEBUG [agent.manager.AgentAttache] (DirectAgent-20:null) Seq 1-1805189139:
No more commands found
2014-09-17 15:08:31,896 DEBUG [agent.transport.Request] (secstorage-1:null) Seq 1-1805189139:
Received:  { Ans: , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 110, { CopyCmdAnswer }
}
2014-09-17 15:08:31,914 INFO  [storage.volume.VolumeServiceImpl] (secstorage-1:null) releasing
lock for VMTemplateStoragePool 4
2014-09-17 15:08:31,915 WARN  [utils.db.Merovingian2] (secstorage-1:null) Was unable to find
lock for the key template_spool_ref4 and thread id 17338152
2014-09-17 15:08:31,915 DEBUG [cloud.storage.VolumeManagerImpl] (secstorage-1:null) Unable
to create Vol[3|vm=3|ROOT]:Catch Exception com.cloud.utils.exception.CloudRuntimeException
for template +  due to com.cloud.utils.exception.CloudRuntimeException: can not create vdi
in sr 50515c54-a7f4-dd0c-a6f2-de8f8cb3edc4
2014-09-17 15:08:31,915 INFO  [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Unable
to contact resource.
com.cloud.exception.StorageUnavailableException: Resource [StoragePool:1] is unreachable:
Unable to create Vol[3|vm=3|ROOT]:Catch Exception com.cloud.utils.exception.CloudRuntimeException
for template +  due to com.cloud.utils.exception.CloudRuntimeException: can not create vdi
in sr 50515c54-a7f4-dd0c-a6f2-de8f8cb3edc4
at com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2590)
at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2641)
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:888)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:570)
at com.cloud.storage.secondary.SecondaryStorageManagerImpl.startSecStorageVm(SecondaryStorageManagerImpl.java:267)
at com.cloud.storage.secondary.SecondaryStorageManagerImpl.allocCapacity(SecondaryStorageManagerImpl.java:696)
at com.cloud.storage.secondary.SecondaryStorageManagerImpl.expandPool(SecondaryStorageManagerImpl.java:1300)
at com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:123)
at com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:50)
at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:104)
at com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
at com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:204)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
2014-09-17 15:08:31,921 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Cleaning
up resources for the vm VM[SecondaryStorageVm|s-3-VM] in Starting state
2014-09-17 15:08:31,926 DEBUG [agent.transport.Request] (secstorage-1:null) Seq 1-1805189140:
Sending  { Cmd , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"s-3-VM","wait":0}}]
}
2014-09-17 15:08:31,926 DEBUG [agent.transport.Request] (secstorage-1:null) Seq 1-1805189140:
Executing:  { Cmd , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"s-3-VM","wait":0}}]
}
2014-09-17 15:08:31,927 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-19:null) Seq
1-1805189140: Executing request
2014-09-17 15:08:32,043 INFO  [xen.resource.CitrixResourceBase] (DirectAgent-19:null) VM does
not exist on XenServerf6d844ab-595d-44df-97fb-44899ff6f008
2014-09-17 15:08:32,043 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-19:null) Seq
1-1805189140: Response Received: 
2014-09-17 15:08:32,044 DEBUG [agent.transport.Request] (DirectAgent-19:null) Seq 1-1805189140:
Processing:  { Ans: , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 110, [{"com.cloud.agent.api.StopAnswer":{"result":true,"details":"VM
does not exist","wait":0}}] }
2014-09-17 15:08:32,044 DEBUG [agent.manager.AgentAttache] (DirectAgent-19:null) Seq 1-1805189140:
No more commands found
2014-09-17 15:08:32,044 DEBUG [agent.transport.Request] (secstorage-1:null) Seq 1-1805189140:
Received:  { Ans: , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 110, { StopAnswer } }
2014-09-17 15:08:32,099 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Asking
VirtualRouter to release Nic[8-3-b93af1ca-658a-47be-a366-06c14f4e2d60-10.1.8.42]
2014-09-17 15:08:32,105 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Asking
SecurityGroupProvider to release Nic[8-3-b93af1ca-658a-47be-a366-06c14f4e2d60-10.1.8.42]
2014-09-17 15:08:32,112 DEBUG [network.guru.ControlNetworkGuru] (secstorage-1:null) Released
nic: NicProfile[9-3-null-null-null
2014-09-17 15:08:32,129 DEBUG [dc.dao.DataCenterIpAddressDaoImpl] (secstorage-1:null) Releasing
ip address for reservationId=b93af1ca-658a-47be-a366-06c14f4e2d60, instance=10
2014-09-17 15:08:32,129 DEBUG [network.guru.PodBasedNetworkGuru] (secstorage-1:null) Released
nic: NicProfile[10-3-null-null-null
2014-09-17 15:08:32,260 DEBUG [dc.dao.DataCenterIpAddressDaoImpl] (secstorage-1:null) Releasing
ip address for reservationId=b93af1ca-658a-47be-a366-06c14f4e2d60, instance=11
2014-09-17 15:08:32,261 DEBUG [network.guru.PodBasedNetworkGuru] (secstorage-1:null) Released
nic: NicProfile[11-3-null-null-null
2014-09-17 15:08:32,319 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Successfully
released network resources for the vm VM[SecondaryStorageVm|s-3-VM]
2014-09-17 15:08:32,319 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Successfully
cleanued up resources for the vm VM[SecondaryStorageVm|s-3-VM] in Starting state
2014-09-17 15:08:32,324 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Deploy avoids pods: null, clusters: null, hosts: [1]
2014-09-17 15:08:32,326 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_dbabbaa1@1542b5b
2014-09-17 15:08:32,331 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Trying to allocate a host and storage pools from dc:1, pod:null,cluster:null, requested cpu:
500, requested ram: 268435456
2014-09-17 15:08:32,331 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Is ROOT volume READY (pool already allocated)?: No
2014-09-17 15:08:32,332 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Searching
all possible resources under this Zone: 1
2014-09-17 15:08:32,334 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Listing clusters
in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity
under this Zone: 1
2014-09-17 15:08:32,357 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Checking resources in Cluster: 1 under Pod: 1
2014-09-17 15:08:32,357 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator)
Looking for hosts in dc: 1  pod:1  cluster:1
2014-09-17 15:08:32,357 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator)
FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]]
2014-09-17 15:08:32,376 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator)
Found 1 hosts for allocation after prioritization: [Host[-1-Routing]]
2014-09-17 15:08:32,376 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator)
Looking for speed=500Mhz, Ram=256
2014-09-17 15:08:32,376 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator)
Host name: xenserver-185, hostId: 1 is in avoid set, skipping this and trying other available
hosts
2014-09-17 15:08:32,376 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator)
Host Allocator returning 0 suitable hosts
2014-09-17 15:08:32,376 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
No suitable hosts found
2014-09-17 15:08:32,376 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
No suitable hosts found under this Cluster: 1
2014-09-17 15:08:32,376 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null)
Could not find suitable Deployment Destination for this VM under any clusters, returning.

2014-09-17 15:08:32,376 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Searching
all possible resources under this Zone: 1
2014-09-17 15:08:32,387 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Listing clusters
in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity
under this Zone: 1
2014-09-17 15:08:32,388 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Removing
from the clusterId list these clusters from avoid set: [1]
2014-09-17 15:08:32,388 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) No clusters
found after removing disabled clusters and clusters in avoid list, returning.
2014-09-17 15:08:32,444 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:null) 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
2014-09-17 15:08:32,457 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:null) Hosts's
actual total CPU: 8512 and CPU after applying overprovisioning: 8512
2014-09-17 15:08:32,457 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:null) Hosts's
actual total RAM: 31571753472 and RAM after applying overprovisioning: 31571752960
2014-09-17 15:08:32,457 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:null) release
cpu from host: 1, old used: 1000,reserved: 0, actual total: 8512, total with overprovisioning:
8512; new used: 500,reserved:0; movedfromreserved: false,moveToReserveredfalse
2014-09-17 15:08:32,457 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:null) release
mem from host: 1, old used: 1342177280,reserved: 0, total: 31571752960; new used: 1073741824,reserved:0;
movedfromreserved: false,moveToReserveredfalse
2014-09-17 15:08:32,464 WARN  [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
Exception while trying to start secondary storage vm
com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for
VM[SecondaryStorageVm|s-3-VM]Scope=interface com.cloud.dc.DataCenter; id=1
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:841)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:570)
at com.cloud.storage.secondary.SecondaryStorageManagerImpl.startSecStorageVm(SecondaryStorageManagerImpl.java:267)
at com.cloud.storage.secondary.SecondaryStorageManagerImpl.allocCapacity(SecondaryStorageManagerImpl.java:696)
at com.cloud.storage.secondary.SecondaryStorageManagerImpl.expandPool(SecondaryStorageManagerImpl.java:1300)
at com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:123)
at com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:50)
at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:104)
at com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
at com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:204)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
2014-09-17 15:08:32,466 INFO  [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
Unable to start secondary storage vm for standby capacity, secStorageVm vm Id : 3, will recycle
it and start a new one
2014-09-17 15:08:32,466 INFO  [cloud.secstorage.PremiumSecondaryStorageManagerImpl] (secstorage-1:null)
Primary secondary storage is not even started, wait until next turn
2014-09-17 15:08:34,687 INFO  [storage.volume.VolumeServiceImpl] (consoleproxy-1:null) Unable
to acquire lock on VMTemplateStoragePool 4
2014-09-17 15:08:34,693 ERROR [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Failed
to start instance VM[ConsoleProxy|v-1-VM]
java.lang.NullPointerException
at org.apache.cloudstack.storage.volume.VolumeServiceImpl.createBaseImageAsync(VolumeServiceImpl.java:428)
at org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromTemplateAsync(VolumeServiceImpl.java:575)
at com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2577)
at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2641)
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:888)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:570)
at com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProxyManagerImpl.java:556)
at com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleProxyManagerImpl.java:928)
at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:1672)
at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:157)
at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:111)
at com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
at com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:204)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
2014-09-17 15:08:34,723 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Cleaning
up resources for the vm VM[ConsoleProxy|v-1-VM] in Starting state
2014-09-17 15:08:34,727 DEBUG [agent.transport.Request] (consoleproxy-1:null) Seq 1-1805189141:
Sending  { Cmd , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"v-1-VM","wait":0}}]
}
2014-09-17 15:08:34,728 DEBUG [agent.transport.Request] (consoleproxy-1:null) Seq 1-1805189141:
Executing:  { Cmd , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"v-1-VM","wait":0}}]
}
2014-09-17 15:08:34,728 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-21:null) Seq
1-1805189141: Executing request
2014-09-17 15:08:34,868 INFO  [xen.resource.CitrixResourceBase] (DirectAgent-21:null) VM does
not exist on XenServerf6d844ab-595d-44df-97fb-44899ff6f008
2014-09-17 15:08:34,868 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-21:null) Seq
1-1805189141: Response Received: 
2014-09-17 15:08:34,868 DEBUG [agent.transport.Request] (DirectAgent-21:null) Seq 1-1805189141:
Processing:  { Ans: , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 110, [{"com.cloud.agent.api.StopAnswer":{"result":true,"details":"VM
does not exist","wait":0}}] }
2014-09-17 15:08:34,868 DEBUG [agent.manager.AgentAttache] (DirectAgent-21:null) Seq 1-1805189141:
No more commands found
2014-09-17 15:08:34,868 DEBUG [agent.transport.Request] (consoleproxy-1:null) Seq 1-1805189141:
Received:  { Ans: , MgmtId: 77174408354289, via: 1, Ver: v1, Flags: 110, { StopAnswer } }
2014-09-17 15:08:34,913 DEBUG [cloud.network.NetworkManagerImpl] (consoleproxy-1:null) Asking
VirtualRouter to release Nic[1-1-c2e8b6da-c4ab-43e3-8de7-a751e5cd7340-10.1.8.41]
2014-09-17 15:08:34,920 DEBUG [cloud.network.NetworkManagerImpl] (consoleproxy-1:null) Asking
SecurityGroupProvider to release Nic[1-1-c2e8b6da-c4ab-43e3-8de7-a751e5cd7340-10.1.8.41]
2014-09-17 15:08:34,932 DEBUG [network.guru.ControlNetworkGuru] (consoleproxy-1:null) Released
nic: NicProfile[2-1-null-null-null
2014-09-17 15:08:34,951 DEBUG [dc.dao.DataCenterIpAddressDaoImpl] (consoleproxy-1:null) Releasing
ip address for reservationId=c2e8b6da-c4ab-43e3-8de7-a751e5cd7340, instance=3
2014-09-17 15:08:34,952 DEBUG [network.guru.PodBasedNetworkGuru] (consoleproxy-1:null) Released
nic: NicProfile[3-1-null-null-null
2014-09-17 15:08:34,958 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Successfully
released network resources for the vm VM[ConsoleProxy|v-1-VM]
2014-09-17 15:08:34,958 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Successfully
cleanued up resources for the vm VM[ConsoleProxy|v-1-VM] in Starting state
2014-09-17 15:08:35,020 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) 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
2014-09-17 15:08:35,037 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) Hosts's
actual total CPU: 8512 and CPU after applying overprovisioning: 8512
2014-09-17 15:08:35,037 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) Hosts's
actual total RAM: 31571753472 and RAM after applying overprovisioning: 31571752960
2014-09-17 15:08:35,037 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) release
cpu from host: 1, old used: 500,reserved: 0, actual total: 8512, total with overprovisioning:
8512; new used: 0,reserved:0; movedfromreserved: false,moveToReserveredfalse
2014-09-17 15:08:35,037 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) release
mem from host: 1, old used: 1073741824,reserved: 0, total: 31571752960; new used: 0,reserved:0;
movedfromreserved: false,moveToReserveredfalse
2014-09-17 15:08:35,046 WARN  [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null)
Exception while trying to start console proxy
com.cloud.exception.AgentUnavailableException: Resource [Host:1] is unreachable: Host 1: Unable
to start instance due to null
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:999)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:570)
at com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProxyManagerImpl.java:556)
at com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleProxyManagerImpl.java:928)
at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:1672)
at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:157)
at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:111)
at com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
at com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441)
at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:317)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:150)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:98)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.runPeriodic(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:204)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
Caused by: java.lang.NullPointerException
at org.apache.cloudstack.storage.volume.VolumeServiceImpl.createBaseImageAsync(VolumeServiceImpl.java:428)
at org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromTemplateAsync(VolumeServiceImpl.java:575)
at com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2577)
at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2641)
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:888)
... 20 more
2014-09-17 15:08:35,324 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null)
Found 0 routers to update status. 
2014-09-17 15:08:35,329 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null)
Found 0 networks to update RvR status. 








在 2014-09-17 11:42:10,"yan_5386@163.com" <yan_5386@163.com> 写道:
>com.cloud.exception.AgentUnavailableException: Resource [Host:18] is unreachable: Host
18: Unable to start instance due to null
>
>用的kvm?agent安装了吗
>到主机的路由是不是正常的
>
>
>yan_5386@163.com
> 
>发件人: 严思恒
>发送时间: 2014-09-16 16:16
>收件人: users-cn@cloudstack.apache.org
>主题: CS4.2.1下系统虚拟机起不来,重复创建!!!!
> 
> 
>CS4.2.1添加过二级存储,里面已经有系统模版,系统虚拟会重复建,log中的异常是下面信息,给指点指点,CS4.0.2的环境测过
二级存储 是可以用的,系统虚拟正常启动,就CS4.2.1起不来,帮帮忙!!!!!!!!
> 
> 
>2014-09-16 15:39:35,858 WARN  [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null)
Exception while trying to start console proxy
>com.cloud.exception.AgentUnavailableException: Resource [Host:18] is unreachable: Host
18: Unable to start instance due to null
>        at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:1010)
>        at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:587)
>        at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:580)
>        at com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProxyManagerImpl.java:556)
>        at com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleProxyManagerImpl.java:928)
>        at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:1672)
>        at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:157)
>        at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:111)
>        at com.cloud.vm.SystemVmLoadScanner.access$100(SystemVmLoadScanner.java:33)
>        at com.cloud.vm.SystemVmLoadScanner$1.reallyRun(SystemVmLoadScanner.java:81)
>        at com.cloud.vm.SystemVmLoadScanner$1.run(SystemVmLoadScanner.java:72)
>        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
>        at java.util.concurrent.FutureTask$Sync.innerRunAndReset(FutureTask.java:351)
>        at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:178)
>        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:165)
>        at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:267)
>        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)
>Caused by: java.lang.NullPointerException
>        at org.apache.cloudstack.storage.volume.VolumeServiceImpl.createBaseImageAsync(VolumeServiceImpl.java:428)
>        at org.apache.cloudstack.storage.volume.VolumeServiceImpl.createVolumeFromTemplateAsync(VolumeServiceImpl.java:575)
>        at com.cloud.storage.VolumeManagerImpl.recreateVolume(VolumeManagerImpl.java:2596)
>        at com.cloud.storage.VolumeManagerImpl.prepare(VolumeManagerImpl.java:2660)
>        at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:899)
>        ... 19 more
Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message