You are viewing a plain text version of this content. The canonical link for it is here.
Posted to users-cn@cloudstack.apache.org by 张秉南 <zb...@gmail.com> on 2014/04/04 08:25:48 UTC

cs4.2+xen6.1 初始化后系统VM启动不了

2014-04-04 00:10:36,740 DEBUG [cloud.server.StatsCollector]
(StatsCollector-3:null) StorageCollector is running...
2014-04-04 00:10:36,748 DEBUG [cloud.server.StatsCollector]
(StatsCollector-3:null) There is no secondary storage VM for secondary
storage host nfs://10.20.6.40/media
2014-04-04 00:10:36,760 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-246:null) Seq 1-766642356: Executing request
2014-04-04 00:10:37,227 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-246:null) Seq 1-766642356: Response Received:
2014-04-04 00:10:37,228 DEBUG [agent.transport.Request]
(StatsCollector-3:null) Seq 1-766642356: Received:  { Ans: , MgmtId:
81397048490778, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2014-04-04 00:10:42,973 DEBUG
[storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
is ready to launch secondary storage VM
2014-04-04 00:10:42,979 INFO
 [cloud.secstorage.PremiumSecondaryStorageManagerImpl] (secstorage-1:null)
No running secondary storage vms found in datacenter id=1, starting one
2014-04-04 00:10:42,989 INFO
 [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Found
a stopped secondary storage vm, bring it up to running pool. secStorageVm
vm id : 381
2014-04-04 00:10:43,001 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) 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
2014-04-04 00:10:43,001 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) Successfully transitioned to start state for
VM[SecondaryStorageVm|s-381-VM] reservation id =
e5d7afe9-4a55-4e3f-95b5-9412e38e8012
2014-04-04 00:10:43,028 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) Trying to deploy VM, vm has dcId: 1 and podId: 1
2014-04-04 00:10:43,028 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) Deploy avoids pods: null, clusters: null, hosts: null
2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) DeploymentPlanner allocation algorithm: random
2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Trying to allocate a host and storage pools from dc:1,
pod:1,cluster:null, requested cpu: 500, requested ram: 268435456
2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Is ROOT volume READY (pool already allocated)?: No
2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Searching resources only under specified Pod: 1
2014-04-04 00:10:43,035 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 Pod: 1
2014-04-04 00:10:43,037 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) CPUOverprovisioningFactor considered: 1.0
2014-04-04 00:10:43,050 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Checking resources in Cluster: 1 under Pod: 1
2014-04-04 00:10:43,050 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1  pod:1
 cluster:1
2014-04-04 00:10:43,051 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to
check for allocation: [Host[-1-Routing]]
2014-04-04 00:10:43,054 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Found 1 hosts for allocation after
prioritization: [Host[-1-Routing]]
2014-04-04 00:10:43,054 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=256
2014-04-04 00:10:43,057 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Checking if host: 1 has enough
capacity for requested CPU: 500 and requested RAM: 268435456 ,
cpuOverprovisioningFactor: 1.0
2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Hosts's actual total CPU: 17552 and
CPU after applying overprovisioning: 17552
2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Free CPU: 17552 , Requested CPU: 500
2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Free RAM: 32844938112 , Requested
RAM: 268435456
2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) Host has enough CPU and RAM
available
2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1,
used: 0, reserved: 0, actual total: 17552, total with overprovisioning:
17552; requested cpu:500,alloc_from_last_host?:false
,considerReservedCapacity?: true
2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1,
used: 0, reserved: 0, total: 32844938112; requested mem:
268435456,alloc_from_last_host?:false ,considerReservedCapacity?: true
2014-04-04 00:10:43,060 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Found a suitable host, adding to
list: 1
2014-04-04 00:10:43,060 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Host Allocator returning 1 suitable
hosts
2014-04-04 00:10:43,061 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Checking suitable pools for volume (Id, Type):
(381,ROOT)
2014-04-04 00:10:43,061 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) We need to allocate new storagepool for this volume
2014-04-04 00:10:43,061 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Calling StoragePoolAllocators to find suitable pools
2014-04-04 00:10:43,062 DEBUG
[storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null)
Looking for pools in dc: 1  pod:1  cluster:1
2014-04-04 00:10:43,063 DEBUG
[storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null)
FirstFitStoragePoolAllocator has 1 pools to check for allocation
2014-04-04 00:10:43,063 DEBUG
[storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null)
Checking if storage pool is suitable, name: primary ,poolId: 200
2014-04-04 00:10:43,064 DEBUG
[storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is
localStorageAllocationNeeded? false
2014-04-04 00:10:43,064 DEBUG
[storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is
storage pool shared? true
2014-04-04 00:10:43,066 DEBUG [cloud.storage.StorageManagerImpl]
(secstorage-1:null) Checking pool 200 for storage, totalSize: 538657882112,
usedBytes: 40663711744, usedPct: 0.07549079498208296, disable threshold:
0.85
2014-04-04 00:10:43,071 DEBUG [cloud.storage.StorageManagerImpl]
(secstorage-1:null) Checking pool: 200 for volume allocation
[Vol[381|vm=381|ROOT]], maxSize : 1077315764224, totalAllocatedSize : 0,
askingSize : 0, allocated disable threshold: 0.85
2014-04-04 00:10:43,071 DEBUG
[storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null)
FirstFitStoragePoolAllocator returning 1 suitable storage pools
2014-04-04 00:10:43,071 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Trying to find a potenial host and associated storage
pools from the suitable host/pool lists for this VM
2014-04-04 00:10:43,071 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Checking if host: 1 can access any suitable storage
pool for volume: ROOT
2014-04-04 00:10:43,072 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Host: 1 can access pool: 200
2014-04-04 00:10:43,072 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Found a potential host id: 1 name: xenserver-6.60 and
associated storage pools for this VM
2014-04-04 00:10:43,074 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Returning Deployment Destination:
Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
:
Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(381|ROOT-->Pool(200))]
2014-04-04 00:10:43,074 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) Deployment found  - P0=VM[SecondaryStorageVm|s-381-VM],
P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
:
Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(381|ROOT-->Pool(200))]
2014-04-04 00:10:43,183 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) VM state transitted from :Starting to Starting with
event: OperationRetryvm's original host id: null new host id: 1 host id
before state transition: null
2014-04-04 00:10:43,195 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) Hosts's actual total CPU: 17552 and CPU after applying
overprovisioning: 17552
2014-04-04 00:10:43,195 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) We are allocating VM, increasing the used capacity of
this host:1
2014-04-04 00:10:43,195 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) Current Used CPU: 0 , Free CPU:17552 ,Requested CPU: 500
2014-04-04 00:10:43,196 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) Current Used RAM: 0 , Free RAM:32844938112 ,Requested
RAM: 268435456
2014-04-04 00:10:43,196 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) CPU STATS after allocation: for host: 1, old used: 0,
old reserved: 0, actual total: 17552, total with overprovisioning: 17552;
new used:500, reserved:0; requested cpu:500,alloc_from_last:false
2014-04-04 00:10:43,196 DEBUG [cloud.capacity.CapacityManagerImpl]
(secstorage-1:null) RAM STATS after allocation: for host: 1, old used: 0,
old reserved: 0, total: 32844938112; new used: 268435456, reserved: 0;
requested mem: 268435456,alloc_from_last:false
2014-04-04 00:10:43,211 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) VM is being created in podId: 1
2014-04-04 00:10:43,219 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Lock is acquired for network id 204 as a part of
network implement
2014-04-04 00:10:43,219 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Network id=204 is already implemented
2014-04-04 00:10:43,220 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Lock is released for network id 204 as a part of
network implement
2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking NiciraNvp to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,280 DEBUG [network.element.NiciraNvpElement]
(secstorage-1:null) Checking if NiciraNvpElement can handle service
Connectivity on network defaultGuestNetwork
2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking JuniperSRX to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking Netscaler to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking F5BigIP to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking CiscoNexus1000vVSM to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking BigSwitchVnsElement to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,281 DEBUG [network.element.BigSwitchVnsElement]
(secstorage-1:null) Checking if BigSwitchVnsElement can handle service
Connectivity on network defaultGuestNetwork
2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking VirtualRouter to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking Ovs to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking SecurityGroupProvider to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking VpcVirtualRouter to prepare for
Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
2014-04-04 00:10:43,281 WARN  [network.element.VpcVirtualRouterElement]
(secstorage-1:null) Network Ntwk[204|Guest|6] is not associated with any VPC
2014-04-04 00:10:43,291 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Lock is acquired for network id 202 as a part of
network implement
2014-04-04 00:10:43,291 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Network id=202 is already implemented
2014-04-04 00:10:43,292 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Lock is released for network id 202 as a part of
network implement
2014-04-04 00:10:43,358 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking NiciraNvp to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,358 DEBUG [network.element.NiciraNvpElement]
(secstorage-1:null) Checking if NiciraNvpElement can handle service
Connectivity on network null
2014-04-04 00:10:43,358 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking JuniperSRX to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking Netscaler to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking F5BigIP to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking CiscoNexus1000vVSM to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking BigSwitchVnsElement to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,359 DEBUG [network.element.BigSwitchVnsElement]
(secstorage-1:null) Checking if BigSwitchVnsElement can handle service
Connectivity on network null
2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking VirtualRouter to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking Ovs to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking SecurityGroupProvider to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Asking VpcVirtualRouter to prepare for
Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
2014-04-04 00:10:43,359 WARN  [network.element.VpcVirtualRouterElement]
(secstorage-1:null) Network Ntwk[202|Control|3] is not associated with any
VPC
2014-04-04 00:10:43,363 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Lock is acquired for network id 201 as a part of
network implement
2014-04-04 00:10:43,363 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Network id=201 is already implemented
2014-04-04 00:10:43,364 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Lock is released for network id 201 as a part of
network implement
2014-04-04 00:10:43,407 DEBUG [db.Transaction.Transaction]
(secstorage-1:null) Rolling back the transaction: Time = 2 Name =
 -SystemVmLoadScanner$1.run:70-Executors$RunnableAdapter.call:471-FutureTask$Sync.innerRunAndReset:351-FutureTask.runAndReset:178-ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201:165-ScheduledThreadPoolExecutor$ScheduledFutureTask.run:267-ThreadPoolExecutor.runWorker:1146-ThreadPoolExecutor$Worker.run:615-Thread.run:701;
called by
-Transaction.rollback:890-DataCenterIpAddressDaoImpl.takeIpAddress:59-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-DataCenterDaoImpl.allocatePrivateIpAddress:230-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-PodBasedNetworkGuru.reserve:119-NetworkManagerImpl.prepareNic:1629-NetworkManagerImpl.prepare:1599-VirtualMachineManagerImpl.advanceStart:746-VirtualMachineManagerImpl.start:471-VirtualMachineManagerImpl.start:464-SecondaryStorageManagerImpl.startSecStorageVm:269
2014-04-04 00:10:43,408 INFO  [cloud.vm.VirtualMachineManagerImpl]
(secstorage-1:null) Insufficient capacity
com.cloud.exception.InsufficientAddressCapacityException: Unable to get a
management ip addressScope=interface com.cloud.dc.Pod; id=1
        at
com.cloud.network.guru.PodBasedNetworkGuru.reserve(PodBasedNetworkGuru.java:121)
        at
com.cloud.network.NetworkManagerImpl.prepareNic(NetworkManagerImpl.java:1629)
        at
com.cloud.network.NetworkManagerImpl.prepare(NetworkManagerImpl.java:1599)
        at
com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:746)
        at
com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:471)
        at
com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:464)
        at
com.cloud.storage.secondary.SecondaryStorageManagerImpl.startSecStorageVm(SecondaryStorageManagerImpl.java:269)
        at
com.cloud.storage.secondary.SecondaryStorageManagerImpl.allocCapacity(SecondaryStorageManagerImpl.java:696)
        at
com.cloud.storage.secondary.SecondaryStorageManagerImpl.expandPool(SecondaryStorageManagerImpl.java:1307)
        at
com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:121)
        at
com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:52)
        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)
:

Re: cs4.2+xen6.1 初始化后系统VM启动不了

Posted by 张秉南 <zb...@gmail.com>.
好的,非常感谢
我重新初始化一次!


2014-04-04 16:07 GMT+08:00 linuxbqj@gmail.com <li...@gmail.com>:

> There is no secondary storage VM for secondary
> storage host nfs://10.20.6.40/media
>
>
> 你检查下NFS存储能否正常挂载,另外你初始化安装了模板之后需要先卸载掉挂载的二级存储的目录,然后再执行初始化
>
> 2014-04-04 14:25 GMT+08:00 张秉南 <zb...@gmail.com>:
> > 2014-04-04 00:10:36,740 DEBUG [cloud.server.StatsCollector]
> > (StatsCollector-3:null) StorageCollector is running...
> > 2014-04-04 00:10:36,748 DEBUG [cloud.server.StatsCollector]
> > (StatsCollector-3:null) There is no secondary storage VM for secondary
> > storage host nfs://10.20.6.40/media
> > 2014-04-04 00:10:36,760 DEBUG [agent.manager.DirectAgentAttache]
> > (DirectAgent-246:null) Seq 1-766642356: Executing request
> > 2014-04-04 00:10:37,227 DEBUG [agent.manager.DirectAgentAttache]
> > (DirectAgent-246:null) Seq 1-766642356: Response Received:
> > 2014-04-04 00:10:37,228 DEBUG [agent.transport.Request]
> > (StatsCollector-3:null) Seq 1-766642356: Received:  { Ans: , MgmtId:
> > 81397048490778, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> > 2014-04-04 00:10:42,973 DEBUG
> > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone
> 1
> > is ready to launch secondary storage VM
> > 2014-04-04 00:10:42,979 INFO
> >  [cloud.secstorage.PremiumSecondaryStorageManagerImpl]
> (secstorage-1:null)
> > No running secondary storage vms found in datacenter id=1, starting one
> > 2014-04-04 00:10:42,989 INFO
> >  [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null)
> Found
> > a stopped secondary storage vm, bring it up to running pool. secStorageVm
> > vm id : 381
> > 2014-04-04 00:10:43,001 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) 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
> > 2014-04-04 00:10:43,001 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) Successfully transitioned to start state for
> > VM[SecondaryStorageVm|s-381-VM] reservation id =
> > e5d7afe9-4a55-4e3f-95b5-9412e38e8012
> > 2014-04-04 00:10:43,028 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) Trying to deploy VM, vm has dcId: 1 and podId: 1
> > 2014-04-04 00:10:43,028 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) Deploy avoids pods: null, clusters: null, hosts: null
> > 2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) DeploymentPlanner allocation algorithm: random
> > 2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Trying to allocate a host and storage pools from
> dc:1,
> > pod:1,cluster:null, requested cpu: 500, requested ram: 268435456
> > 2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Is ROOT volume READY (pool already allocated)?: No
> > 2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Searching resources only under specified Pod: 1
> > 2014-04-04 00:10:43,035 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 Pod:
> 1
> > 2014-04-04 00:10:43,037 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) CPUOverprovisioningFactor considered: 1.0
> > 2014-04-04 00:10:43,050 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Checking resources in Cluster: 1 under Pod: 1
> > 2014-04-04 00:10:43,050 DEBUG [allocator.impl.FirstFitAllocator]
> > (secstorage-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1  pod:1
> >  cluster:1
> > 2014-04-04 00:10:43,051 DEBUG [allocator.impl.FirstFitAllocator]
> > (secstorage-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to
> > check for allocation: [Host[-1-Routing]]
> > 2014-04-04 00:10:43,054 DEBUG [allocator.impl.FirstFitAllocator]
> > (secstorage-1:FirstFitRoutingAllocator) Found 1 hosts for allocation
> after
> > prioritization: [Host[-1-Routing]]
> > 2014-04-04 00:10:43,054 DEBUG [allocator.impl.FirstFitAllocator]
> > (secstorage-1:FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=256
> > 2014-04-04 00:10:43,057 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:FirstFitRoutingAllocator) Checking if host: 1 has enough
> > capacity for requested CPU: 500 and requested RAM: 268435456 ,
> > cpuOverprovisioningFactor: 1.0
> > 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:FirstFitRoutingAllocator) Hosts's actual total CPU: 17552
> and
> > CPU after applying overprovisioning: 17552
> > 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:FirstFitRoutingAllocator) Free CPU: 17552 , Requested CPU:
> 500
> > 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:FirstFitRoutingAllocator) Free RAM: 32844938112 , Requested
> > RAM: 268435456
> > 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:FirstFitRoutingAllocator) Host has enough CPU and RAM
> > available
> > 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from host:
> 1,
> > used: 0, reserved: 0, actual total: 17552, total with overprovisioning:
> > 17552; requested cpu:500,alloc_from_last_host?:false
> > ,considerReservedCapacity?: true
> > 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host:
> 1,
> > used: 0, reserved: 0, total: 32844938112; requested mem:
> > 268435456,alloc_from_last_host?:false ,considerReservedCapacity?: true
> > 2014-04-04 00:10:43,060 DEBUG [allocator.impl.FirstFitAllocator]
> > (secstorage-1:FirstFitRoutingAllocator) Found a suitable host, adding to
> > list: 1
> > 2014-04-04 00:10:43,060 DEBUG [allocator.impl.FirstFitAllocator]
> > (secstorage-1:FirstFitRoutingAllocator) Host Allocator returning 1
> suitable
> > hosts
> > 2014-04-04 00:10:43,061 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Checking suitable pools for volume (Id, Type):
> > (381,ROOT)
> > 2014-04-04 00:10:43,061 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) We need to allocate new storagepool for this volume
> > 2014-04-04 00:10:43,061 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Calling StoragePoolAllocators to find suitable pools
> > 2014-04-04 00:10:43,062 DEBUG
> > [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null)
> > Looking for pools in dc: 1  pod:1  cluster:1
> > 2014-04-04 00:10:43,063 DEBUG
> > [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null)
> > FirstFitStoragePoolAllocator has 1 pools to check for allocation
> > 2014-04-04 00:10:43,063 DEBUG
> > [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null)
> > Checking if storage pool is suitable, name: primary ,poolId: 200
> > 2014-04-04 00:10:43,064 DEBUG
> > [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is
> > localStorageAllocationNeeded? false
> > 2014-04-04 00:10:43,064 DEBUG
> > [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is
> > storage pool shared? true
> > 2014-04-04 00:10:43,066 DEBUG [cloud.storage.StorageManagerImpl]
> > (secstorage-1:null) Checking pool 200 for storage, totalSize:
> 538657882112,
> > usedBytes: 40663711744, usedPct: 0.07549079498208296, disable threshold:
> > 0.85
> > 2014-04-04 00:10:43,071 DEBUG [cloud.storage.StorageManagerImpl]
> > (secstorage-1:null) Checking pool: 200 for volume allocation
> > [Vol[381|vm=381|ROOT]], maxSize : 1077315764224, totalAllocatedSize : 0,
> > askingSize : 0, allocated disable threshold: 0.85
> > 2014-04-04 00:10:43,071 DEBUG
> > [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null)
> > FirstFitStoragePoolAllocator returning 1 suitable storage pools
> > 2014-04-04 00:10:43,071 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Trying to find a potenial host and associated storage
> > pools from the suitable host/pool lists for this VM
> > 2014-04-04 00:10:43,071 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Checking if host: 1 can access any suitable storage
> > pool for volume: ROOT
> > 2014-04-04 00:10:43,072 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Host: 1 can access pool: 200
> > 2014-04-04 00:10:43,072 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Found a potential host id: 1 name: xenserver-6.60 and
> > associated storage pools for this VM
> > 2014-04-04 00:10:43,074 DEBUG [cloud.deploy.FirstFitPlanner]
> > (secstorage-1:null) Returning Deployment Destination:
> >
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
> > :
> >
> Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(381|ROOT-->Pool(200))]
> > 2014-04-04 00:10:43,074 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) Deployment found  -
> P0=VM[SecondaryStorageVm|s-381-VM],
> >
> P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
> > :
> >
> Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(381|ROOT-->Pool(200))]
> > 2014-04-04 00:10:43,183 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) VM state transitted from :Starting to Starting with
> > event: OperationRetryvm's original host id: null new host id: 1 host id
> > before state transition: null
> > 2014-04-04 00:10:43,195 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) Hosts's actual total CPU: 17552 and CPU after
> applying
> > overprovisioning: 17552
> > 2014-04-04 00:10:43,195 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) We are allocating VM, increasing the used capacity of
> > this host:1
> > 2014-04-04 00:10:43,195 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) Current Used CPU: 0 , Free CPU:17552 ,Requested CPU:
> 500
> > 2014-04-04 00:10:43,196 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) Current Used RAM: 0 , Free RAM:32844938112 ,Requested
> > RAM: 268435456
> > 2014-04-04 00:10:43,196 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) CPU STATS after allocation: for host: 1, old used: 0,
> > old reserved: 0, actual total: 17552, total with overprovisioning: 17552;
> > new used:500, reserved:0; requested cpu:500,alloc_from_last:false
> > 2014-04-04 00:10:43,196 DEBUG [cloud.capacity.CapacityManagerImpl]
> > (secstorage-1:null) RAM STATS after allocation: for host: 1, old used: 0,
> > old reserved: 0, total: 32844938112; new used: 268435456, reserved: 0;
> > requested mem: 268435456,alloc_from_last:false
> > 2014-04-04 00:10:43,211 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) VM is being created in podId: 1
> > 2014-04-04 00:10:43,219 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Lock is acquired for network id 204 as a part of
> > network implement
> > 2014-04-04 00:10:43,219 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Network id=204 is already implemented
> > 2014-04-04 00:10:43,220 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Lock is released for network id 204 as a part of
> > network implement
> > 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking NiciraNvp to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,280 DEBUG [network.element.NiciraNvpElement]
> > (secstorage-1:null) Checking if NiciraNvpElement can handle service
> > Connectivity on network defaultGuestNetwork
> > 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking JuniperSRX to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking Netscaler to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking F5BigIP to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking CiscoNexus1000vVSM to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking BigSwitchVnsElement to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,281 DEBUG [network.element.BigSwitchVnsElement]
> > (secstorage-1:null) Checking if BigSwitchVnsElement can handle service
> > Connectivity on network defaultGuestNetwork
> > 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking VirtualRouter to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking Ovs to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking SecurityGroupProvider to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking VpcVirtualRouter to prepare for
> > Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> > 2014-04-04 00:10:43,281 WARN  [network.element.VpcVirtualRouterElement]
> > (secstorage-1:null) Network Ntwk[204|Guest|6] is not associated with any
> VPC
> > 2014-04-04 00:10:43,291 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Lock is acquired for network id 202 as a part of
> > network implement
> > 2014-04-04 00:10:43,291 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Network id=202 is already implemented
> > 2014-04-04 00:10:43,292 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Lock is released for network id 202 as a part of
> > network implement
> > 2014-04-04 00:10:43,358 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking NiciraNvp to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,358 DEBUG [network.element.NiciraNvpElement]
> > (secstorage-1:null) Checking if NiciraNvpElement can handle service
> > Connectivity on network null
> > 2014-04-04 00:10:43,358 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking JuniperSRX to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking Netscaler to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking F5BigIP to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking CiscoNexus1000vVSM to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking BigSwitchVnsElement to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,359 DEBUG [network.element.BigSwitchVnsElement]
> > (secstorage-1:null) Checking if BigSwitchVnsElement can handle service
> > Connectivity on network null
> > 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking VirtualRouter to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking Ovs to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking SecurityGroupProvider to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Asking VpcVirtualRouter to prepare for
> > Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> > 2014-04-04 00:10:43,359 WARN  [network.element.VpcVirtualRouterElement]
> > (secstorage-1:null) Network Ntwk[202|Control|3] is not associated with
> any
> > VPC
> > 2014-04-04 00:10:43,363 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Lock is acquired for network id 201 as a part of
> > network implement
> > 2014-04-04 00:10:43,363 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Network id=201 is already implemented
> > 2014-04-04 00:10:43,364 DEBUG [cloud.network.NetworkManagerImpl]
> > (secstorage-1:null) Lock is released for network id 201 as a part of
> > network implement
> > 2014-04-04 00:10:43,407 DEBUG [db.Transaction.Transaction]
> > (secstorage-1:null) Rolling back the transaction: Time = 2 Name =
> >
>  -SystemVmLoadScanner$1.run:70-Executors$RunnableAdapter.call:471-FutureTask$Sync.innerRunAndReset:351-FutureTask.runAndReset:178-ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201:165-ScheduledThreadPoolExecutor$ScheduledFutureTask.run:267-ThreadPoolExecutor.runWorker:1146-ThreadPoolExecutor$Worker.run:615-Thread.run:701;
> > called by
> >
> -Transaction.rollback:890-DataCenterIpAddressDaoImpl.takeIpAddress:59-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-DataCenterDaoImpl.allocatePrivateIpAddress:230-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-PodBasedNetworkGuru.reserve:119-NetworkManagerImpl.prepareNic:1629-NetworkManagerImpl.prepare:1599-VirtualMachineManagerImpl.advanceStart:746-VirtualMachineManagerImpl.start:471-VirtualMachineManagerImpl.start:464-SecondaryStorageManagerImpl.startSecStorageVm:269
> > 2014-04-04 00:10:43,408 INFO  [cloud.vm.VirtualMachineManagerImpl]
> > (secstorage-1:null) Insufficient capacity
> > com.cloud.exception.InsufficientAddressCapacityException: Unable to get a
> > management ip addressScope=interface com.cloud.dc.Pod; id=1
> >         at
> >
> com.cloud.network.guru.PodBasedNetworkGuru.reserve(PodBasedNetworkGuru.java:121)
> >         at
> >
> com.cloud.network.NetworkManagerImpl.prepareNic(NetworkManagerImpl.java:1629)
> >         at
> >
> com.cloud.network.NetworkManagerImpl.prepare(NetworkManagerImpl.java:1599)
> >         at
> >
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:746)
> >         at
> >
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:471)
> >         at
> >
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:464)
> >         at
> >
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.startSecStorageVm(SecondaryStorageManagerImpl.java:269)
> >         at
> >
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.allocCapacity(SecondaryStorageManagerImpl.java:696)
> >         at
> >
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.expandPool(SecondaryStorageManagerImpl.java:1307)
> >         at
> >
> com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:121)
> >         at
> >
> com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:52)
> >         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)
> > :
>
>
>
> --
> 白清杰 (Born Bai)
>
> 北京开源愿景信息技术有限公司
>
> Mail: linuxbqj@gmail.com
>

Re: cs4.2+xen6.1 初始化后系统VM启动不了

Posted by "linuxbqj@gmail.com" <li...@gmail.com>.
There is no secondary storage VM for secondary
storage host nfs://10.20.6.40/media


你检查下NFS存储能否正常挂载,另外你初始化安装了模板之后需要先卸载掉挂载的二级存储的目录,然后再执行初始化

2014-04-04 14:25 GMT+08:00 张秉南 <zb...@gmail.com>:
> 2014-04-04 00:10:36,740 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-3:null) StorageCollector is running...
> 2014-04-04 00:10:36,748 DEBUG [cloud.server.StatsCollector]
> (StatsCollector-3:null) There is no secondary storage VM for secondary
> storage host nfs://10.20.6.40/media
> 2014-04-04 00:10:36,760 DEBUG [agent.manager.DirectAgentAttache]
> (DirectAgent-246:null) Seq 1-766642356: Executing request
> 2014-04-04 00:10:37,227 DEBUG [agent.manager.DirectAgentAttache]
> (DirectAgent-246:null) Seq 1-766642356: Response Received:
> 2014-04-04 00:10:37,228 DEBUG [agent.transport.Request]
> (StatsCollector-3:null) Seq 1-766642356: Received:  { Ans: , MgmtId:
> 81397048490778, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
> 2014-04-04 00:10:42,973 DEBUG
> [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1
> is ready to launch secondary storage VM
> 2014-04-04 00:10:42,979 INFO
>  [cloud.secstorage.PremiumSecondaryStorageManagerImpl] (secstorage-1:null)
> No running secondary storage vms found in datacenter id=1, starting one
> 2014-04-04 00:10:42,989 INFO
>  [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Found
> a stopped secondary storage vm, bring it up to running pool. secStorageVm
> vm id : 381
> 2014-04-04 00:10:43,001 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:null) 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
> 2014-04-04 00:10:43,001 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (secstorage-1:null) Successfully transitioned to start state for
> VM[SecondaryStorageVm|s-381-VM] reservation id =
> e5d7afe9-4a55-4e3f-95b5-9412e38e8012
> 2014-04-04 00:10:43,028 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (secstorage-1:null) Trying to deploy VM, vm has dcId: 1 and podId: 1
> 2014-04-04 00:10:43,028 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (secstorage-1:null) Deploy avoids pods: null, clusters: null, hosts: null
> 2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) DeploymentPlanner allocation algorithm: random
> 2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Trying to allocate a host and storage pools from dc:1,
> pod:1,cluster:null, requested cpu: 500, requested ram: 268435456
> 2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Is ROOT volume READY (pool already allocated)?: No
> 2014-04-04 00:10:43,032 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Searching resources only under specified Pod: 1
> 2014-04-04 00:10:43,035 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 Pod: 1
> 2014-04-04 00:10:43,037 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) CPUOverprovisioningFactor considered: 1.0
> 2014-04-04 00:10:43,050 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Checking resources in Cluster: 1 under Pod: 1
> 2014-04-04 00:10:43,050 DEBUG [allocator.impl.FirstFitAllocator]
> (secstorage-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1  pod:1
>  cluster:1
> 2014-04-04 00:10:43,051 DEBUG [allocator.impl.FirstFitAllocator]
> (secstorage-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to
> check for allocation: [Host[-1-Routing]]
> 2014-04-04 00:10:43,054 DEBUG [allocator.impl.FirstFitAllocator]
> (secstorage-1:FirstFitRoutingAllocator) Found 1 hosts for allocation after
> prioritization: [Host[-1-Routing]]
> 2014-04-04 00:10:43,054 DEBUG [allocator.impl.FirstFitAllocator]
> (secstorage-1:FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=256
> 2014-04-04 00:10:43,057 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:FirstFitRoutingAllocator) Checking if host: 1 has enough
> capacity for requested CPU: 500 and requested RAM: 268435456 ,
> cpuOverprovisioningFactor: 1.0
> 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:FirstFitRoutingAllocator) Hosts's actual total CPU: 17552 and
> CPU after applying overprovisioning: 17552
> 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:FirstFitRoutingAllocator) Free CPU: 17552 , Requested CPU: 500
> 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:FirstFitRoutingAllocator) Free RAM: 32844938112 , Requested
> RAM: 268435456
> 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:FirstFitRoutingAllocator) Host has enough CPU and RAM
> available
> 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1,
> used: 0, reserved: 0, actual total: 17552, total with overprovisioning:
> 17552; requested cpu:500,alloc_from_last_host?:false
> ,considerReservedCapacity?: true
> 2014-04-04 00:10:43,060 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1,
> used: 0, reserved: 0, total: 32844938112; requested mem:
> 268435456,alloc_from_last_host?:false ,considerReservedCapacity?: true
> 2014-04-04 00:10:43,060 DEBUG [allocator.impl.FirstFitAllocator]
> (secstorage-1:FirstFitRoutingAllocator) Found a suitable host, adding to
> list: 1
> 2014-04-04 00:10:43,060 DEBUG [allocator.impl.FirstFitAllocator]
> (secstorage-1:FirstFitRoutingAllocator) Host Allocator returning 1 suitable
> hosts
> 2014-04-04 00:10:43,061 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Checking suitable pools for volume (Id, Type):
> (381,ROOT)
> 2014-04-04 00:10:43,061 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) We need to allocate new storagepool for this volume
> 2014-04-04 00:10:43,061 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Calling StoragePoolAllocators to find suitable pools
> 2014-04-04 00:10:43,062 DEBUG
> [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null)
> Looking for pools in dc: 1  pod:1  cluster:1
> 2014-04-04 00:10:43,063 DEBUG
> [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null)
> FirstFitStoragePoolAllocator has 1 pools to check for allocation
> 2014-04-04 00:10:43,063 DEBUG
> [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null)
> Checking if storage pool is suitable, name: primary ,poolId: 200
> 2014-04-04 00:10:43,064 DEBUG
> [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is
> localStorageAllocationNeeded? false
> 2014-04-04 00:10:43,064 DEBUG
> [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is
> storage pool shared? true
> 2014-04-04 00:10:43,066 DEBUG [cloud.storage.StorageManagerImpl]
> (secstorage-1:null) Checking pool 200 for storage, totalSize: 538657882112,
> usedBytes: 40663711744, usedPct: 0.07549079498208296, disable threshold:
> 0.85
> 2014-04-04 00:10:43,071 DEBUG [cloud.storage.StorageManagerImpl]
> (secstorage-1:null) Checking pool: 200 for volume allocation
> [Vol[381|vm=381|ROOT]], maxSize : 1077315764224, totalAllocatedSize : 0,
> askingSize : 0, allocated disable threshold: 0.85
> 2014-04-04 00:10:43,071 DEBUG
> [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null)
> FirstFitStoragePoolAllocator returning 1 suitable storage pools
> 2014-04-04 00:10:43,071 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Trying to find a potenial host and associated storage
> pools from the suitable host/pool lists for this VM
> 2014-04-04 00:10:43,071 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Checking if host: 1 can access any suitable storage
> pool for volume: ROOT
> 2014-04-04 00:10:43,072 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Host: 1 can access pool: 200
> 2014-04-04 00:10:43,072 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Found a potential host id: 1 name: xenserver-6.60 and
> associated storage pools for this VM
> 2014-04-04 00:10:43,074 DEBUG [cloud.deploy.FirstFitPlanner]
> (secstorage-1:null) Returning Deployment Destination:
> Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
> :
> Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(381|ROOT-->Pool(200))]
> 2014-04-04 00:10:43,074 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (secstorage-1:null) Deployment found  - P0=VM[SecondaryStorageVm|s-381-VM],
> P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
> :
> Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(381|ROOT-->Pool(200))]
> 2014-04-04 00:10:43,183 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:null) VM state transitted from :Starting to Starting with
> event: OperationRetryvm's original host id: null new host id: 1 host id
> before state transition: null
> 2014-04-04 00:10:43,195 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:null) Hosts's actual total CPU: 17552 and CPU after applying
> overprovisioning: 17552
> 2014-04-04 00:10:43,195 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:null) We are allocating VM, increasing the used capacity of
> this host:1
> 2014-04-04 00:10:43,195 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:null) Current Used CPU: 0 , Free CPU:17552 ,Requested CPU: 500
> 2014-04-04 00:10:43,196 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:null) Current Used RAM: 0 , Free RAM:32844938112 ,Requested
> RAM: 268435456
> 2014-04-04 00:10:43,196 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:null) CPU STATS after allocation: for host: 1, old used: 0,
> old reserved: 0, actual total: 17552, total with overprovisioning: 17552;
> new used:500, reserved:0; requested cpu:500,alloc_from_last:false
> 2014-04-04 00:10:43,196 DEBUG [cloud.capacity.CapacityManagerImpl]
> (secstorage-1:null) RAM STATS after allocation: for host: 1, old used: 0,
> old reserved: 0, total: 32844938112; new used: 268435456, reserved: 0;
> requested mem: 268435456,alloc_from_last:false
> 2014-04-04 00:10:43,211 DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (secstorage-1:null) VM is being created in podId: 1
> 2014-04-04 00:10:43,219 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Lock is acquired for network id 204 as a part of
> network implement
> 2014-04-04 00:10:43,219 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Network id=204 is already implemented
> 2014-04-04 00:10:43,220 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Lock is released for network id 204 as a part of
> network implement
> 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking NiciraNvp to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,280 DEBUG [network.element.NiciraNvpElement]
> (secstorage-1:null) Checking if NiciraNvpElement can handle service
> Connectivity on network defaultGuestNetwork
> 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking JuniperSRX to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking Netscaler to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking F5BigIP to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,280 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking CiscoNexus1000vVSM to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking BigSwitchVnsElement to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,281 DEBUG [network.element.BigSwitchVnsElement]
> (secstorage-1:null) Checking if BigSwitchVnsElement can handle service
> Connectivity on network defaultGuestNetwork
> 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking VirtualRouter to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking Ovs to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking SecurityGroupProvider to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,281 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking VpcVirtualRouter to prepare for
> Nic[1520-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-10.20.6.62]
> 2014-04-04 00:10:43,281 WARN  [network.element.VpcVirtualRouterElement]
> (secstorage-1:null) Network Ntwk[204|Guest|6] is not associated with any VPC
> 2014-04-04 00:10:43,291 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Lock is acquired for network id 202 as a part of
> network implement
> 2014-04-04 00:10:43,291 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Network id=202 is already implemented
> 2014-04-04 00:10:43,292 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Lock is released for network id 202 as a part of
> network implement
> 2014-04-04 00:10:43,358 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking NiciraNvp to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,358 DEBUG [network.element.NiciraNvpElement]
> (secstorage-1:null) Checking if NiciraNvpElement can handle service
> Connectivity on network null
> 2014-04-04 00:10:43,358 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking JuniperSRX to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking Netscaler to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking F5BigIP to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking CiscoNexus1000vVSM to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking BigSwitchVnsElement to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,359 DEBUG [network.element.BigSwitchVnsElement]
> (secstorage-1:null) Checking if BigSwitchVnsElement can handle service
> Connectivity on network null
> 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking VirtualRouter to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking Ovs to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking SecurityGroupProvider to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,359 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Asking VpcVirtualRouter to prepare for
> Nic[1521-381-e5d7afe9-4a55-4e3f-95b5-9412e38e8012-169.254.1.185]
> 2014-04-04 00:10:43,359 WARN  [network.element.VpcVirtualRouterElement]
> (secstorage-1:null) Network Ntwk[202|Control|3] is not associated with any
> VPC
> 2014-04-04 00:10:43,363 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Lock is acquired for network id 201 as a part of
> network implement
> 2014-04-04 00:10:43,363 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Network id=201 is already implemented
> 2014-04-04 00:10:43,364 DEBUG [cloud.network.NetworkManagerImpl]
> (secstorage-1:null) Lock is released for network id 201 as a part of
> network implement
> 2014-04-04 00:10:43,407 DEBUG [db.Transaction.Transaction]
> (secstorage-1:null) Rolling back the transaction: Time = 2 Name =
>  -SystemVmLoadScanner$1.run:70-Executors$RunnableAdapter.call:471-FutureTask$Sync.innerRunAndReset:351-FutureTask.runAndReset:178-ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201:165-ScheduledThreadPoolExecutor$ScheduledFutureTask.run:267-ThreadPoolExecutor.runWorker:1146-ThreadPoolExecutor$Worker.run:615-Thread.run:701;
> called by
> -Transaction.rollback:890-DataCenterIpAddressDaoImpl.takeIpAddress:59-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-DataCenterDaoImpl.allocatePrivateIpAddress:230-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-PodBasedNetworkGuru.reserve:119-NetworkManagerImpl.prepareNic:1629-NetworkManagerImpl.prepare:1599-VirtualMachineManagerImpl.advanceStart:746-VirtualMachineManagerImpl.start:471-VirtualMachineManagerImpl.start:464-SecondaryStorageManagerImpl.startSecStorageVm:269
> 2014-04-04 00:10:43,408 INFO  [cloud.vm.VirtualMachineManagerImpl]
> (secstorage-1:null) Insufficient capacity
> com.cloud.exception.InsufficientAddressCapacityException: Unable to get a
> management ip addressScope=interface com.cloud.dc.Pod; id=1
>         at
> com.cloud.network.guru.PodBasedNetworkGuru.reserve(PodBasedNetworkGuru.java:121)
>         at
> com.cloud.network.NetworkManagerImpl.prepareNic(NetworkManagerImpl.java:1629)
>         at
> com.cloud.network.NetworkManagerImpl.prepare(NetworkManagerImpl.java:1599)
>         at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:746)
>         at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:471)
>         at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:464)
>         at
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.startSecStorageVm(SecondaryStorageManagerImpl.java:269)
>         at
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.allocCapacity(SecondaryStorageManagerImpl.java:696)
>         at
> com.cloud.storage.secondary.SecondaryStorageManagerImpl.expandPool(SecondaryStorageManagerImpl.java:1307)
>         at
> com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:121)
>         at
> com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(PremiumSecondaryStorageManagerImpl.java:52)
>         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)
> :



-- 
白清杰 (Born Bai)

北京开源愿景信息技术有限公司

Mail: linuxbqj@gmail.com