CS有vhd-util,xenserver也有在/opt/xensource/bin
在 2014-09-17 03:48:08，"Wei ZHOU" <ustcweiz...@gmail.com> 写道：
>有vhd-util吗？
>
>2014-09-17 9:43 GMT+02:00 严思恒 <yansiheng...@163.com>:
>
>>
>>
>>
>> CS是4.2.1，使用的基础网络，启用本地存储，hypervisor用的是xenserver6.2.0，管理网段10.1.8.1-10.1.8.40，来宾网段10.1.8.41-10.1.8.100，二级存储NFS类型，系统模板是从官网下载并解压好的，二级存储没问题，在4.0.2+xenserver6.2.0中正常使用，可以起来系统虚拟机，CS4.0.2支持xenserver6.2.0是后来加的。
>>
>> CS4.2.1+xenserver6.2.0+NFS二级存储，最开始报的错误的地方往下的日志如下：
>>
>> 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 01:40:55，"Darren Tang" <darrentang...@gmail.com> 写道：
>> >把你的环境描述下。 管理端，哪种虚拟化，高级网络还是基本网络，网络怎么规划的等等。这样贴日志，不太好查原因。
>> >
>> >2014-09-17 11:42 GMT+08:00 yan_5...@163.com <yan_5...@163.com>:
>> >
>> >> com.cloud.exception.AgentUnavailableException: Resource [Host:18] is
>> >> unreachable: Host 18: Unable to start instance due to null
>> >>
>> >> 用的kvm?agent安装了吗
>> >> 到主机的路由是不是正常的
>> >>
>> >>
>> >> yan_5...@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
>> >>
>>
