cloudstack-users-cn mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From 张秉南 <zbnyouj...@gmail.com>
Subject cs4.2+xen6.1 初始化后系统VM启动不了
Date Fri, 04 Apr 2014 06:25:48 GMT
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)
:

Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message