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 andylauyang <an...@126.com> on 2014/04/13 15:42:57 UTC
回复: 回复: cloudstack中SSVM启动报错
可以给个可靠一点的模板下载地址吗，我明天再试试换个模板装下
发件人： andylauyang
发送时间： 2014-04-13 16:21
收件人： cloudstack中文邮件组
主题： 回复: Re: cloudstack中SSVM启动报错
创建模板过程：
#cd /usr/share/cloudstack-common/scripts/storage/secondary/
#./cloud-install-sys-tmplt -m /secondary/ -f /ova/acton-systemvm-02062012.vhd.bz2 -h xenserver -F
最后反馈成功了，你说有可能是这个模板包有问题吗？
发件人： Shuiping Xie
发送时间： 2014-04-13 15:28
收件人： users-cn@cloudstack.apache.org
抄送： andylauyang
主题： Re: Re: cloudstack中SSVM启动报错
如能正常挂载，那就是你的SSVM准备的有问题了，重新做下用正确好使的ssvm template.
发自我的小米手机
linuxbqj@gmail.com编写：
你检查下NFS能否正常挂载。另外你安装xenserver的系统模板了吗？
安装系统模板之后，需要umount
2014-04-13 15:05 GMT+08:00 andylauyang <an...@126.com>:
> 首先很感谢您在百忙之中能耐心的看我的问题，并给予我回复，我将我的配置情况给您先看看：
> 我在VMware WorkStation下安装的两个虚机，一台作cloudstack管理（IP为192.168.248.10），另一台作XenServer（192.168.248.200），在管理上安装NFS作为一级存储和二级存储
> selinux 设置为permissive , iptables 我直接关闭了
> 我的虚拟机里的网段为192.168.248.0/24
> 进入WEB-UI后，首先设置“全局设置”secstorage.allowed.internal.sites值为192.168.248.0/24 , management.network.cidr为192.168.248.0/24
> 接下来添加区域，提供点(预留的系统网关192.168.248.1，预留的系统网络掩码255.255.255.0，起始预留系统IP192.168.248.20，结束预留系统IP192.168.248.30)
> 接下来设置“来宾流量”（来宾网关192.168.248.1，来宾网络掩码255.255.255.0，来宾起始IP192.168.248.100，来宾结束IP，192.168.248.150）
> 然后添加集群和主机，主存储用的NFS(服务器为cloudstack管理的IP192.168.248.10，Path为/pri)，辅助存储也是NFS（cloudstack管理的IP192.168.248.10，Path为/sec）
> 最后启动，一切显示正常完成，最后Console Proxy VM和Secondary Storage VM却无法启动
>
> 以上是我的配置情况，再次谢谢您
>
>
>
>
>
> 发件人： linuxbqj@gmail.com
> 发送时间： 2014-04-13 14:14
> 收件人： users-cn; andylauyang
> 主题： Re: cloudstack中SSVM启动报错
> 看日志，是你的存储有问题。
>
> 建议说明下你的基本环境信息，以及做过哪些操作了
>
> 2014-04-13 12:37 GMT+08:00 andylauyang <an...@126.com>:
>> 2014-04-13 11:57:38,991 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers to update status.
>> 2014-04-13 11:57:38,992 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterMonitor-1:null) Found 0 running routers.
>> 2014-04-13 11:57:38,995 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
>> 2014-04-13 11:57:39,129 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterMonitor-1:null) Found 0 running routers.
>> 2014-04-13 11:57:39,132 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers to update status.
>> 2014-04-13 11:57:39,135 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
>> 2014-04-13 11:57:39,439 DEBUG [cloud.network.ExternalLoadBalancerUsageManagerImpl] (ExternalNetworkMonitor-1:null) External devices stats collector is running...
>> 2014-04-13 11:57:39,445 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Snapshot scheduler.poll is being called at 2014-04-13 03:57:39 GMT
>> 2014-04-13 11:57:39,447 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Got 0 snapshots to be executed at 2014-04-13 03:57:39 GMT
>> 2014-04-13 11:57:45,963 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-17:null) Ping from 1
>> 2014-04-13 11:57:46,733 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-5:null) Seq 1-1313341445: Executing request
>> 2014-04-13 11:57:46,932 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-5:null) Seq 1-1313341445: Response Received:
>> 2014-04-13 11:57:46,933 DEBUG [agent.transport.Request] (DirectAgent-5:null) Seq 1-1313341445: Processing: { Ans: , MgmtId: 52235347610, via: 1, Ver: v1, Flags: 10, [{"ClusterSyncAnswer":{"_clusterId":1,"_newStates":{},"_isExecuted":false,"result":true,"wait":0}}] }
>> 2014-04-13 11:57:54,237 DEBUG [cloud.server.StatsCollector] (StatsCollector-3:null) VmStatsCollector is running...
>> 2014-04-13 11:57:56,137 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) HostStatsCollector is running...
>> 2014-04-13 11:57:56,151 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-1:null) Seq 1-1313341463: Executing request
>> 2014-04-13 11:57:56,336 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-1:null) Seq 1-1313341463: Response Received:
>> 2014-04-13 11:57:56,336 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-1313341463: Received: { Ans: , MgmtId: 52235347610, via: 1, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
>> 2014-04-13 11:57:57,223 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) StorageCollector is running...
>> 2014-04-13 11:57:57,232 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) There is no secondary storage VM for secondary storage host nfs://192.168.248.10/sec
>> 2014-04-13 11:57:57,261 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-18:null) Seq 1-1313341464: Executing request
>> 2014-04-13 11:57:57,542 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-18:null) Seq 1-1313341464: Response Received:
>> 2014-04-13 11:57:57,542 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-1313341464: Received: { Ans: , MgmtId: 52235347610, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
>> 2014-04-13 11:58:07,780 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
>> 2014-04-13 11:58:07,786 INFO [cloud.secstorage.PremiumSecondaryStorageManagerImpl] (secstorage-1:null) No running secondary storage vms found in datacenter id=1, starting one
>> 2014-04-13 11:58:07,795 INFO [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) No stopped secondary storage vm is available, need to allocate a new secondary storage vm
>> 2014-04-13 11:58:07,796 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Assign secondary storage vm from a newly started instance for request from data center : 1
>> 2014-04-13 11:58:07,806 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network configuration for offering [Network Offering [6-Guest-DefaultSharedNetworkOfferingWithSGService]: Ntwk[204|Guest|6]
>> 2014-04-13 11:58:07,806 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for Acct[1-system]
>> 2014-04-13 11:58:07,816 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network configuration for offering [Network Offering [3-Control-System-Control-Network]: Ntwk[202|Control|3]
>> 2014-04-13 11:58:07,816 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for Acct[1-system]
>> 2014-04-13 11:58:07,821 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network configuration for offering [Network Offering [2-Management-System-Management-Network]: Ntwk[201|Management|2]
>> 2014-04-13 11:58:07,821 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for Acct[1-system]
>> 2014-04-13 11:58:07,824 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network configuration for offering [Network Offering [4-Storage-System-Storage-Network]: Ntwk[203|Storage|4]
>> 2014-04-13 11:58:07,824 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for Acct[1-system]
>> 2014-04-13 11:58:07,830 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocating entries for VM: VM[SecondaryStorageVm|s-256-VM]
>> 2014-04-13 11:58:07,835 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocating nics for VM[SecondaryStorageVm|s-256-VM]
>> 2014-04-13 11:58:07,836 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in network Ntwk[204|Guest|6] with requested profile NicProfile[0-0-null-null-null
>> 2014-04-13 11:58:07,856 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in network Ntwk[202|Control|3] with requested profile null
>> 2014-04-13 11:58:07,864 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in network Ntwk[201|Management|2] with requested profile null
>> 2014-04-13 11:58:07,901 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in network Ntwk[203|Storage|4] with requested profile null
>> 2014-04-13 11:58:07,919 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocaing disks for VM[SecondaryStorageVm|s-256-VM]
>> 2014-04-13 11:58:07,924 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocation completed for VM: VM[SecondaryStorageVm|s-256-VM]
>> 2014-04-13 11:58:07,927 DEBUG [cloud.alert.SecondaryStorageVmAlertAdapter] (secstorage-1:null) received secondary storage vm alert
>> 2014-04-13 11:58:07,927 DEBUG [cloud.alert.SecondaryStorageVmAlertAdapter] (secstorage-1:null) New secondary storage vm created, zone: xenzone, secStorageVm: s-256-VM, public IP: null, private IP: null
>> 2014-04-13 11:58:07,941 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-13 11:58:07,941 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Successfully transitioned to start state for VM[SecondaryStorageVm|s-256-VM] reservation id = 9ec9e9ea-1efa-444d-a5d1-b0f1bb6e0908
>> 2014-04-13 11:58:07,945 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Trying to deploy VM, vm has dcId: 1 and podId: null
>> 2014-04-13 11:58:07,945 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Deploy avoids pods: null, clusters: null, hosts: null
>> 2014-04-13 11:58:07,947 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) DeploymentPlanner allocation algorithm: random
>> 2014-04-13 11:58:07,947 DEBUG [cloud.deploy.FirstFitPlanner] (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-04-13 11:58:07,947 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Is ROOT volume READY (pool already allocated)?: No
>> 2014-04-13 11:58:07,947 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Searching all possible resources under this Zone: 1
>> 2014-04-13 11:58:07,948 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-04-13 11:58:07,949 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) CPUOverprovisioningFactor considered: 1.0
>> 2014-04-13 11:58:07,957 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Checking resources in Cluster: 1 under Pod: 1
>> 2014-04-13 11:58:07,958 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1
>> 2014-04-13 11:58:07,960 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]]
>> 2014-04-13 11:58:07,969 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Found 1 hosts for allocation after prioritization: [Host[-1-Routing]]
>> 2014-04-13 11:58:07,970 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=256
>> 2014-04-13 11:58:07,973 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-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) Hosts's actual total CPU: 2493 and CPU after applying overprovisioning: 2493
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) Free CPU: 2493 , Requested CPU: 500
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) Free RAM: 1671630912 , Requested RAM: 268435456
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) Host has enough CPU and RAM available
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1, used: 0, reserved: 0, actual total: 2493, total with overprovisioning: 2493; requested cpu:500,alloc_from_last_host?:false ,considerReservedCapacity?: true
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1, used: 0, reserved: 0, total: 1671630912; requested mem: 268435456,alloc_from_last_host?:false ,considerReservedCapacity?: true
>> 2014-04-13 11:58:07,976 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Found a suitable host, adding to list: 1
>> 2014-04-13 11:58:07,976 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Host Allocator returning 1 suitable hosts
>> 2014-04-13 11:58:07,978 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Checking suitable pools for volume (Id, Type): (256,ROOT)
>> 2014-04-13 11:58:07,978 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) We need to allocate new storagepool for this volume
>> 2014-04-13 11:58:07,978 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Calling StoragePoolAllocators to find suitable pools
>> 2014-04-13 11:58:07,979 DEBUG [storage.allocator.LocalStoragePoolAllocator] (secstorage-1:null) LocalStoragePoolAllocator trying to find storage pool to fit the vm
>> 2014-04-13 11:58:07,979 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null) Looking for pools in dc: 1 pod:1 cluster:1
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null) FirstFitStoragePoolAllocator has 1 pools to check for allocation
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Checking if storage pool is suitable, name: primary ,poolId: 215
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is localStorageAllocationNeeded? true
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is storage pool shared? true
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) StoragePool is not of correct type, skipping this pool
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null) FirstFitStoragePoolAllocator returning 0 suitable storage pools
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.LocalStoragePoolAllocator] (secstorage-1:null) LocalStoragePoolAllocator returning 0 suitable storage pools
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.LocalStoragePoolAllocator] (secstorage-1:null) Unable to find storage pool to fit the vm
>> 2014-04-13 11:58:07,981 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) No suitable pools found for volume: Vol[256|vm=256|ROOT] under cluster: 1
>> 2014-04-13 11:58:07,981 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) No suitable pools found
>> 2014-04-13 11:58:07,981 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) No suitable storagePools found under this Cluster: 1
>> 2014-04-13 11:58:07,981 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Could not find suitable Deployment Destination for this VM under any clusters, returning.
>> 2014-04-13 11:58:07,987 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: null
>> 2014-04-13 11:58:07,988 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-256-VM]Scope=interface com.cloud.dc.DataCenter; id=1
>> at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:728)
>> 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)
>> 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:1110)
>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
>> at java.lang.Thread.run(Thread.java:679)
>> 2014-04-13 11:58:07,989 INFO [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Unable to start secondary storage vm for standby capacity, secStorageVm vm Id : 256, will recycle it and start a new one
>> 2014-04-13 11:58:07,989 INFO [cloud.secstorage.PremiumSecondaryStorageManagerImpl] (secstorage-1:null) Primary secondary storage is not even started, wait until next turn
>> 2014-04-13 11:58:08,297 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
>> 2014-04-13 11:58:08,297 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Expand console proxy standby capacity for zone xenzone
>> 2014-04-13 11:58:08,300 INFO [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Found a stopped console proxy, bring it up to running pool. proxy vm id : 197
>> 2014-04-13 11:58:08,313 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-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-13 11:58:08,313 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Successfully transitioned to start state for VM[ConsoleProxy|v-197-VM] reservation id = ed8bc4e4-f9f2-4ea6-a25f-fc5e4815d1db
>> 2014-04-13 11:58:08,328 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Trying to deploy VM, vm has dcId: 1 and podId: null
>> 2014-04-13 11:58:08,328 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Deploy avoids pods: null, clusters: null, hosts: null
>> 2014-04-13 11:58:08,332 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) DeploymentPlanner allocation algorithm: random
>> 2014-04-13 11:58:08,333 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Trying to allocate a host and storage pools from dc:1, pod:null,cluster:null, requested cpu: 500, requested ram: 1073741824
>> 2014-04-13 11:58:08,333 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Is ROOT volume READY (pool already allocated)?: No
>> 2014-04-13 11:58:08,333 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Searching all possible resources under this Zone: 1
>> 2014-04-13 11:58:08,336 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-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-04-13 11:58:08,337 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) CPUOverprovisioningFactor considered: 1.0
>> 2014-04-13 11:58:08,347 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Checking resources in Cluster: 1 under Pod: 1
>> 2014-04-13 11:58:08,347 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1
>> 2014-04-13 11:58:08,349 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]]
>> 2014-04-13 11:58:08,351 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Found 1 hosts for allocation after prioritization: [Host[-1-Routing]]
>> 2014-04-13 11:58:08,351 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=1024
>> 2014-04-13 11:58:08,355 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Checking if host: 1 has enough capacity for requested CPU: 500 and requested RAM: 1073741824 , cpuOverprovisioningFactor: 1.0
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Hosts's actual total CPU: 2493 and CPU after applying overprovisioning: 2493
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Free CPU: 2493 , Requested CPU: 500
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Free RAM: 1671630912 , Requested RAM: 1073741824
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Host has enough CPU and RAM available
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1, used: 0, reserved: 0, actual total: 2493, total with overprovisioning: 2493; requested cpu:500,alloc_from_last_host?:false ,considerReservedCapacity?: true
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1, used: 0, reserved: 0, total: 1671630912; requested mem: 1073741824,alloc_from_last_host?:false ,considerReservedCapacity?: true
>> 2014-04-13 11:58:08,358 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Found a suitable host, adding to list: 1
>> 2014-04-13 11:58:08,358 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Host Allocator returning 1 suitable hosts
>> 2014-04-13 11:58:08,365 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Checking suitable pools for volume (Id, Type): (197,ROOT)
>> 2014-04-13 11:58:08,365 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) We need to allocate new storagepool for this volume
>> 2014-04-13 11:58:08,366 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Calling StoragePoolAllocators to find suitable pools
>> 2014-04-13 11:58:08,368 DEBUG [storage.allocator.LocalStoragePoolAllocator] (consoleproxy-1:null) LocalStoragePoolAllocator trying to find storage pool to fit the vm
>> 2014-04-13 11:58:08,368 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (consoleproxy-1:null) Looking for pools in dc: 1 pod:1 cluster:1
>> 2014-04-13 11:58:08,370 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (consoleproxy-1:null) FirstFitStoragePoolAllocator has 1 pools to check for allocation
>> 2014-04-13 11:58:08,370 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (consoleproxy-1:null) Checking if storage pool is suitable, name: primary ,poolId: 215
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (consoleproxy-1:null) Is localStorageAllocationNeeded? true
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (consoleproxy-1:null) Is storage pool shared? true
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (consoleproxy-1:null) StoragePool is not of correct type, skipping this pool
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (consoleproxy-1:null) FirstFitStoragePoolAllocator returning 0 suitable storage pools
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.LocalStoragePoolAllocator] (consoleproxy-1:null) LocalStoragePoolAllocator returning 0 suitable storage pools
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.LocalStoragePoolAllocator] (consoleproxy-1:null) Unable to find storage pool to fit the vm
>> 2014-04-13 11:58:08,371 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) No suitable pools found for volume: Vol[197|vm=197|ROOT] under cluster: 1
>> 2014-04-13 11:58:08,371 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) No suitable pools found
>> 2014-04-13 11:58:08,371 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) No suitable storagePools found under this Cluster: 1
>> 2014-04-13 11:58:08,372 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Could not find suitable Deployment Destination for this VM under any clusters, returning.
>> 2014-04-13 11:58:08,379 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: null
>> 2014-04-13 11:58:08,381 WARN [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Exception while trying to start console proxy
>> com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[ConsoleProxy|v-197-VM]Scope=interface com.cloud.dc.DataCenter; id=1
>> at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:728)
>> at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:471)
>> at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:464)
>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProxyManagerImpl.java:632)
>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleProxyManagerImpl.java:1166)
>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:1985)
>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:175)
>> 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:1110)
>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
>> at java.lang.Thread.run(Thread.java:679)
>
>
>
> --
> 白清杰 (Born Bai)
>
> 北京开源愿景信息技术有限公司
>
> Mail: linuxbqj@gmail.com
--
白清杰 (Born Bai)
北京开源愿景信息技术有限公司
Mail: linuxbqj@gmail.com
回复: 回复: cloudstack中SSVM启动报错
Posted by tanthalas <ta...@hotmail.com>.
模板暂时看没有问题，因为log里没有报与这个相关的错误。log里边有这样两句是判断问题的原因：?StoragePool is not of correct type, skipping this pool?Unable to find storage pool to fit the vm所以只可能是你的NFS存储配置问题。
刘宇超
?发件人：?andylauyang发送时间：?2014-04-13?21:42收件人：?cloudstack中文邮件组主题：?回复: 回复: cloudstack中SSVM启动报错可以给个可靠一点的模板下载地址吗，我明天再试试换个模板装下
?
?
?
发件人： andylauyang
发送时间： 2014-04-13 16:21
收件人： cloudstack中文邮件组
主题： 回复: Re: cloudstack中SSVM启动报错
创建模板过程：
#cd /usr/share/cloudstack-common/scripts/storage/secondary/
#./cloud-install-sys-tmplt -m /secondary/ -f /ova/acton-systemvm-02062012.vhd.bz2 -h xenserver -F
最后反馈成功了，你说有可能是这个模板包有问题吗？
?
?
?
?
?
发件人： Shuiping Xie
发送时间： 2014-04-13 15:28
收件人： users-cn@cloudstack.apache.org
抄送： andylauyang
主题： Re: Re: cloudstack中SSVM启动报错
如能正常挂载，那就是你的SSVM准备的有问题了，重新做下用正确好使的ssvm? template.
?
?
?
发自我的小米手机
?
linuxbqj@gmail.com编写：
?
?
你检查下NFS能否正常挂载。另外你安装xenserver的系统模板了吗？
安装系统模板之后，需要umount
?
2014-04-13 15:05 GMT+08:00 andylauyang <an...@126.com>:
> 首先很感谢您在百忙之中能耐心的看我的问题，并给予我回复，我将我的配置情况给您先看看：
> 我在VMware WorkStation下安装的两个虚机，一台作cloudstack管理（IP为192.168.248.10），另一台作XenServer（192.168.248.200），在管理上安装NFS作为一级存储和二级存储
> selinux 设置为permissive , iptables 我直接关闭了
> 我的虚拟机里的网段为192.168.248.0/24
> 进入WEB-UI后，首先设置“全局设置”secstorage.allowed.internal.sites值为192.168.248.0/24? , management.network.cidr为192.168.248.0/24
> 接下来添加区域，提供点(预留的系统网关192.168.248.1，预留的系统网络掩码255.255.255.0，起始预留系统IP192.168.248.20，结束预留系统IP192.168.248.30)
> 接下来设置“来宾流量”（来宾网关192.168.248.1，来宾网络掩码255.255.255.0，来宾起始IP192.168.248.100，来宾结束IP，192.168.248.150）
> 然后添加集群和主机，主存储用的NFS(服务器为cloudstack管理的IP192.168.248.10，Path为/pri)，辅助存储也是NFS（cloudstack管理的IP192.168.248.10，Path为/sec）
> 最后启动，一切显示正常完成，最后Console Proxy VM和Secondary Storage VM却无法启动
>
> 以上是我的配置情况，再次谢谢您
>
>
>
>
>
> 发件人： linuxbqj@gmail.com
> 发送时间： 2014-04-13 14:14
> 收件人： users-cn; andylauyang
> 主题： Re: cloudstack中SSVM启动报错
> 看日志，是你的存储有问题。
>
> 建议说明下你的基本环境信息，以及做过哪些操作了
>
> 2014-04-13 12:37 GMT+08:00 andylauyang <an...@126.com>:
>> 2014-04-13 11:57:38,991 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers to update status.
>> 2014-04-13 11:57:38,992 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterMonitor-1:null) Found 0 running routers.
>> 2014-04-13 11:57:38,995 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
>> 2014-04-13 11:57:39,129 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterMonitor-1:null) Found 0 running routers.
>> 2014-04-13 11:57:39,132 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers to update status.
>> 2014-04-13 11:57:39,135 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
>> 2014-04-13 11:57:39,439 DEBUG [cloud.network.ExternalLoadBalancerUsageManagerImpl] (ExternalNetworkMonitor-1:null) External devices stats collector is running...
>> 2014-04-13 11:57:39,445 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Snapshot scheduler.poll is being called at 2014-04-13 03:57:39 GMT
>> 2014-04-13 11:57:39,447 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Got 0 snapshots to be executed at 2014-04-13 03:57:39 GMT
>> 2014-04-13 11:57:45,963 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-17:null) Ping from 1
>> 2014-04-13 11:57:46,733 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-5:null) Seq 1-1313341445: Executing request
>> 2014-04-13 11:57:46,932 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-5:null) Seq 1-1313341445: Response Received:
>> 2014-04-13 11:57:46,933 DEBUG [agent.transport.Request] (DirectAgent-5:null) Seq 1-1313341445: Processing:? { Ans: , MgmtId: 52235347610, via: 1, Ver: v1, Flags: 10, [{"ClusterSyncAnswer":{"_clusterId":1,"_newStates":{},"_isExecuted":false,"result":true,"wait":0}}] }
>> 2014-04-13 11:57:54,237 DEBUG [cloud.server.StatsCollector] (StatsCollector-3:null) VmStatsCollector is running...
>> 2014-04-13 11:57:56,137 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) HostStatsCollector is running...
>> 2014-04-13 11:57:56,151 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-1:null) Seq 1-1313341463: Executing request
>> 2014-04-13 11:57:56,336 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-1:null) Seq 1-1313341463: Response Received:
>> 2014-04-13 11:57:56,336 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-1313341463: Received:? { Ans: , MgmtId: 52235347610, via: 1, Ver: v1, Flags: 10, { GetHostStatsAnswer } }
>> 2014-04-13 11:57:57,223 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) StorageCollector is running...
>> 2014-04-13 11:57:57,232 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) There is no secondary storage VM for secondary storage host nfs://192.168.248.10/sec
>> 2014-04-13 11:57:57,261 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-18:null) Seq 1-1313341464: Executing request
>> 2014-04-13 11:57:57,542 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-18:null) Seq 1-1313341464: Response Received:
>> 2014-04-13 11:57:57,542 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 1-1313341464: Received:? { Ans: , MgmtId: 52235347610, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
>> 2014-04-13 11:58:07,780 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
>> 2014-04-13 11:58:07,786 INFO? [cloud.secstorage.PremiumSecondaryStorageManagerImpl] (secstorage-1:null) No running secondary storage vms found in datacenter id=1, starting one
>> 2014-04-13 11:58:07,795 INFO? [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) No stopped secondary storage vm is available, need to allocate a new secondary storage vm
>> 2014-04-13 11:58:07,796 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Assign secondary storage vm from a newly started instance for request from data center : 1
>> 2014-04-13 11:58:07,806 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network configuration for offering [Network Offering [6-Guest-DefaultSharedNetworkOfferingWithSGService]: Ntwk[204|Guest|6]
>> 2014-04-13 11:58:07,806 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for Acct[1-system]
>> 2014-04-13 11:58:07,816 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network configuration for offering [Network Offering [3-Control-System-Control-Network]: Ntwk[202|Control|3]
>> 2014-04-13 11:58:07,816 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for Acct[1-system]
>> 2014-04-13 11:58:07,821 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network configuration for offering [Network Offering [2-Management-System-Management-Network]: Ntwk[201|Management|2]
>> 2014-04-13 11:58:07,821 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for Acct[1-system]
>> 2014-04-13 11:58:07,824 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network configuration for offering [Network Offering [4-Storage-System-Storage-Network]: Ntwk[203|Storage|4]
>> 2014-04-13 11:58:07,824 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for Acct[1-system]
>> 2014-04-13 11:58:07,830 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocating entries for VM: VM[SecondaryStorageVm|s-256-VM]
>> 2014-04-13 11:58:07,835 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocating nics for VM[SecondaryStorageVm|s-256-VM]
>> 2014-04-13 11:58:07,836 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in network Ntwk[204|Guest|6] with requested profile NicProfile[0-0-null-null-null
>> 2014-04-13 11:58:07,856 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in network Ntwk[202|Control|3] with requested profile null
>> 2014-04-13 11:58:07,864 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in network Ntwk[201|Management|2] with requested profile null
>> 2014-04-13 11:58:07,901 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in network Ntwk[203|Storage|4] with requested profile null
>> 2014-04-13 11:58:07,919 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocaing disks for VM[SecondaryStorageVm|s-256-VM]
>> 2014-04-13 11:58:07,924 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocation completed for VM: VM[SecondaryStorageVm|s-256-VM]
>> 2014-04-13 11:58:07,927 DEBUG [cloud.alert.SecondaryStorageVmAlertAdapter] (secstorage-1:null) received secondary storage vm alert
>> 2014-04-13 11:58:07,927 DEBUG [cloud.alert.SecondaryStorageVmAlertAdapter] (secstorage-1:null) New secondary storage vm created, zone: xenzone, secStorageVm: s-256-VM, public IP: null, private IP: null
>> 2014-04-13 11:58:07,941 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-13 11:58:07,941 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Successfully transitioned to start state for VM[SecondaryStorageVm|s-256-VM] reservation id = 9ec9e9ea-1efa-444d-a5d1-b0f1bb6e0908
>> 2014-04-13 11:58:07,945 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Trying to deploy VM, vm has dcId: 1 and podId: null
>> 2014-04-13 11:58:07,945 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Deploy avoids pods: null, clusters: null, hosts: null
>> 2014-04-13 11:58:07,947 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) DeploymentPlanner allocation algorithm: random
>> 2014-04-13 11:58:07,947 DEBUG [cloud.deploy.FirstFitPlanner] (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-04-13 11:58:07,947 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Is ROOT volume READY (pool already allocated)?: No
>> 2014-04-13 11:58:07,947 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Searching all possible resources under this Zone: 1
>> 2014-04-13 11:58:07,948 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-04-13 11:58:07,949 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) CPUOverprovisioningFactor considered: 1.0
>> 2014-04-13 11:58:07,957 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Checking resources in Cluster: 1 under Pod: 1
>> 2014-04-13 11:58:07,958 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1? pod:1? cluster:1
>> 2014-04-13 11:58:07,960 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]]
>> 2014-04-13 11:58:07,969 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Found 1 hosts for allocation after prioritization: [Host[-1-Routing]]
>> 2014-04-13 11:58:07,970 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=256
>> 2014-04-13 11:58:07,973 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-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) Hosts's actual total CPU: 2493 and CPU after applying overprovisioning: 2493
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) Free CPU: 2493 , Requested CPU: 500
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) Free RAM: 1671630912 , Requested RAM: 268435456
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) Host has enough CPU and RAM available
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1, used: 0, reserved: 0, actual total: 2493, total with overprovisioning: 2493; requested cpu:500,alloc_from_last_host?:false ,considerReservedCapacity?: true
>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secstorage-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1, used: 0, reserved: 0, total: 1671630912; requested mem: 268435456,alloc_from_last_host?:false ,considerReservedCapacity?: true
>> 2014-04-13 11:58:07,976 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Found a suitable host, adding to list: 1
>> 2014-04-13 11:58:07,976 DEBUG [allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator) Host Allocator returning 1 suitable hosts
>> 2014-04-13 11:58:07,978 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Checking suitable pools for volume (Id, Type): (256,ROOT)
>> 2014-04-13 11:58:07,978 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) We need to allocate new storagepool for this volume
>> 2014-04-13 11:58:07,978 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Calling StoragePoolAllocators to find suitable pools
>> 2014-04-13 11:58:07,979 DEBUG [storage.allocator.LocalStoragePoolAllocator] (secstorage-1:null) LocalStoragePoolAllocator trying to find storage pool to fit the vm
>> 2014-04-13 11:58:07,979 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null) Looking for pools in dc: 1? pod:1? cluster:1
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null) FirstFitStoragePoolAllocator has 1 pools to check for allocation
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Checking if storage pool is suitable, name: primary ,poolId: 215
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is localStorageAllocationNeeded? true
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) Is storage pool shared? true
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (secstorage-1:null) StoragePool is not of correct type, skipping this pool
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (secstorage-1:null) FirstFitStoragePoolAllocator returning 0 suitable storage pools
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.LocalStoragePoolAllocator] (secstorage-1:null) LocalStoragePoolAllocator returning 0 suitable storage pools
>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.LocalStoragePoolAllocator] (secstorage-1:null) Unable to find storage pool to fit the vm
>> 2014-04-13 11:58:07,981 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) No suitable pools found for volume: Vol[256|vm=256|ROOT] under cluster: 1
>> 2014-04-13 11:58:07,981 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) No suitable pools found
>> 2014-04-13 11:58:07,981 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) No suitable storagePools found under this Cluster: 1
>> 2014-04-13 11:58:07,981 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Could not find suitable Deployment Destination for this VM under any clusters, returning.
>> 2014-04-13 11:58:07,987 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: null
>> 2014-04-13 11:58:07,988 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-256-VM]Scope=interface com.cloud.dc.DataCenter; id=1
>> at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:728)
>> 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)
>> 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:1110)
>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
>> at java.lang.Thread.run(Thread.java:679)
>> 2014-04-13 11:58:07,989 INFO? [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Unable to start secondary storage vm for standby capacity, secStorageVm vm Id : 256, will recycle it and start a new one
>> 2014-04-13 11:58:07,989 INFO? [cloud.secstorage.PremiumSecondaryStorageManagerImpl] (secstorage-1:null) Primary secondary storage is not even started, wait until next turn
>> 2014-04-13 11:58:08,297 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
>> 2014-04-13 11:58:08,297 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Expand console proxy standby capacity for zone xenzone
>> 2014-04-13 11:58:08,300 INFO? [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Found a stopped console proxy, bring it up to running pool. proxy vm id : 197
>> 2014-04-13 11:58:08,313 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-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-13 11:58:08,313 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Successfully transitioned to start state for VM[ConsoleProxy|v-197-VM] reservation id = ed8bc4e4-f9f2-4ea6-a25f-fc5e4815d1db
>> 2014-04-13 11:58:08,328 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Trying to deploy VM, vm has dcId: 1 and podId: null
>> 2014-04-13 11:58:08,328 DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Deploy avoids pods: null, clusters: null, hosts: null
>> 2014-04-13 11:58:08,332 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) DeploymentPlanner allocation algorithm: random
>> 2014-04-13 11:58:08,333 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Trying to allocate a host and storage pools from dc:1, pod:null,cluster:null, requested cpu: 500, requested ram: 1073741824
>> 2014-04-13 11:58:08,333 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Is ROOT volume READY (pool already allocated)?: No
>> 2014-04-13 11:58:08,333 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Searching all possible resources under this Zone: 1
>> 2014-04-13 11:58:08,336 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-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-04-13 11:58:08,337 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) CPUOverprovisioningFactor considered: 1.0
>> 2014-04-13 11:58:08,347 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Checking resources in Cluster: 1 under Pod: 1
>> 2014-04-13 11:58:08,347 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1? pod:1? cluster:1
>> 2014-04-13 11:58:08,349 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for allocation: [Host[-1-Routing]]
>> 2014-04-13 11:58:08,351 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Found 1 hosts for allocation after prioritization: [Host[-1-Routing]]
>> 2014-04-13 11:58:08,351 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=1024
>> 2014-04-13 11:58:08,355 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Checking if host: 1 has enough capacity for requested CPU: 500 and requested RAM: 1073741824 , cpuOverprovisioningFactor: 1.0
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Hosts's actual total CPU: 2493 and CPU after applying overprovisioning: 2493
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Free CPU: 2493 , Requested CPU: 500
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Free RAM: 1671630912 , Requested RAM: 1073741824
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) Host has enough CPU and RAM available
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1, used: 0, reserved: 0, actual total: 2493, total with overprovisioning: 2493; requested cpu:500,alloc_from_last_host?:false ,considerReservedCapacity?: true
>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (consoleproxy-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1, used: 0, reserved: 0, total: 1671630912; requested mem: 1073741824,alloc_from_last_host?:false ,considerReservedCapacity?: true
>> 2014-04-13 11:58:08,358 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Found a suitable host, adding to list: 1
>> 2014-04-13 11:58:08,358 DEBUG [allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator) Host Allocator returning 1 suitable hosts
>> 2014-04-13 11:58:08,365 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Checking suitable pools for volume (Id, Type): (197,ROOT)
>> 2014-04-13 11:58:08,365 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) We need to allocate new storagepool for this volume
>> 2014-04-13 11:58:08,366 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Calling StoragePoolAllocators to find suitable pools
>> 2014-04-13 11:58:08,368 DEBUG [storage.allocator.LocalStoragePoolAllocator] (consoleproxy-1:null) LocalStoragePoolAllocator trying to find storage pool to fit the vm
>> 2014-04-13 11:58:08,368 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (consoleproxy-1:null) Looking for pools in dc: 1? pod:1? cluster:1
>> 2014-04-13 11:58:08,370 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (consoleproxy-1:null) FirstFitStoragePoolAllocator has 1 pools to check for allocation
>> 2014-04-13 11:58:08,370 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (consoleproxy-1:null) Checking if storage pool is suitable, name: primary ,poolId: 215
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (consoleproxy-1:null) Is localStorageAllocationNeeded? true
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (consoleproxy-1:null) Is storage pool shared? true
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (consoleproxy-1:null) StoragePool is not of correct type, skipping this pool
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.FirstFitStoragePoolAllocator] (consoleproxy-1:null) FirstFitStoragePoolAllocator returning 0 suitable storage pools
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.LocalStoragePoolAllocator] (consoleproxy-1:null) LocalStoragePoolAllocator returning 0 suitable storage pools
>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.LocalStoragePoolAllocator] (consoleproxy-1:null) Unable to find storage pool to fit the vm
>> 2014-04-13 11:58:08,371 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) No suitable pools found for volume: Vol[197|vm=197|ROOT] under cluster: 1
>> 2014-04-13 11:58:08,371 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) No suitable pools found
>> 2014-04-13 11:58:08,371 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) No suitable storagePools found under this Cluster: 1
>> 2014-04-13 11:58:08,372 DEBUG [cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Could not find suitable Deployment Destination for this VM under any clusters, returning.
>> 2014-04-13 11:58:08,379 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: null
>> 2014-04-13 11:58:08,381 WARN? [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Exception while trying to start console proxy
>> com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[ConsoleProxy|v-197-VM]Scope=interface com.cloud.dc.DataCenter; id=1
>> at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:728)
>> at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:471)
>> at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:464)
>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProxyManagerImpl.java:632)
>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleProxyManagerImpl.java:1166)
>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:1985)
>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProxyManagerImpl.java:175)
>> 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:1110)
>> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
>> at java.lang.Thread.run(Thread.java:679)
>
>
>
> --
> 白清杰 (Born Bai)
>
> 北京开源愿景信息技术有限公司
>
> Mail: linuxbqj@gmail.com
?
?
?
--
白清杰 (Born Bai)
?
北京开源愿景信息技术有限公司
?
Mail: linuxbqj@gmail.com