Return-Path: X-Original-To: apmail-cloudstack-users-cn-archive@www.apache.org Delivered-To: apmail-cloudstack-users-cn-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 659C410D12 for ; Mon, 14 Apr 2014 05:03:00 +0000 (UTC) Received: (qmail 3946 invoked by uid 500); 14 Apr 2014 05:03:00 -0000 Delivered-To: apmail-cloudstack-users-cn-archive@cloudstack.apache.org Received: (qmail 3924 invoked by uid 500); 14 Apr 2014 05:02:59 -0000 Mailing-List: contact users-cn-help@cloudstack.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: users-cn@cloudstack.apache.org Delivered-To: mailing list users-cn@cloudstack.apache.org Received: (qmail 3914 invoked by uid 99); 14 Apr 2014 05:02:58 -0000 Received: from athena.apache.org (HELO athena.apache.org) (140.211.11.136) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 14 Apr 2014 05:02:58 +0000 X-ASF-Spam-Status: No, hits=0.0 required=5.0 tests=MIME_QP_LONG_LINE,RCVD_IN_DNSWL_NONE,SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (athena.apache.org: domain of cz_wangji@163.com designates 220.181.12.13 as permitted sender) Received: from [220.181.12.13] (HELO m12-13.163.com) (220.181.12.13) by apache.org (qpsmtpd/0.29) with ESMTP; Mon, 14 Apr 2014 05:02:51 +0000 DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=163.com; s=s110527; h=Subject:From:Message-Id:Date:Mime-Version; bh=gWY9y Qa5xhqV01QUrFnaQPyOyMtyDjP+HIRF6Lc6/O4=; b=N/KX2OPxZ2F/xz2R76xGR i+DinsCCZmtVsAODLoyL1WtbtX6lNEyDahVS0tcxHt62P+7eYnJ7EPrFVURtp2QV 8LzVY12pRpOdLKknqnBR42BI1twXeGTd9CV79lCt6rLfl1/e+wlWhPwJwYDR8S0F bbw6JvI+E0EFTX8NL0O//M= Received: from [192.168.42.203] (unknown [220.202.153.53]) by smtp9 (Coremail) with SMTP id DcCowEBJcnnba0tTIXIkAQ--.12070S2; Mon, 14 Apr 2014 13:02:21 +0800 (CST) Subject: =?GB2312?Q?Re:_cloudstack=D6=D0SSVM=C6=F4=B6=AF=B1=A8=B4=ED?= References: <201404131237449844607@126.com> <2014041315053324372237@126.com> From: Ji Wang Content-Type: text/plain; charset=gb2312 X-Mailer: iPad Mail (11B554a) In-Reply-To: Message-Id: <674AA4A8-582E-4ABA-87DE-0058C2231A59@163.com> Date: Mon, 14 Apr 2014 13:02:16 +0800 To: "users-cn@cloudstack.apache.org" Content-Transfer-Encoding: quoted-printable Mime-Version: 1.0 (1.0) X-CM-TRANSID: DcCowEBJcnnba0tTIXIkAQ--.12070S2 X-Coremail-Antispam: 1Uf129KBjvAXoWfKry7Kw47uw17ZF13tw13Arb_yoW5Zr17Wo Wrtrsavw4DXryUXa4Utrn7uF4UZrWjgr4DJw1fZ34UGa92q34IvFWq9F4rXFnIkry3XF1U ZFyUWa4IyFW7JF1fn29KB7ZKAUJUUUUU529EdanIXcx71UUUUU7v73VFW2AGmfu7bjvjm3 AaLaJ3UbIYCTnIWIevJa73UjIFyTuYvj4R9J5OUUUUU X-Originating-IP: [220.202.153.53] X-CM-SenderInfo: 5f2b4tpqjmxqqrwthudrp/xtbBERdtKlD+VkSkCAAAsR X-Virus-Checked: Checked by ClamAV on apache.org =C8=E7=B9=FBNFS=BA=CDmanager=D4=DA=D2=BB=CC=A8=BB=FA=C6=F7=C9=CF=A3=AC=CA=C7= =B2=BB=CA=C7=D2=B2=D2=BB=B6=A8=D2=AAumount?=B0=B2=D7=B0=CA=D6=B2=E1=D6=D0=CB= =C6=BA=F5=C3=BB=D3=D0=D0=B4=B5=BD=D5=E2=B8=F6 =B7=A2=D7=D4=CE=D2=B5=C4 iPad > =D4=DA 2014=C4=EA4=D4=C213=C8=D5=A3=AC15:16=A3=AC"linuxbqj@gmail.com" =D0=B4=B5=C0=A3=BA >=20 > =C4=E3=BC=EC=B2=E9=CF=C2NFS=C4=DC=B7=F1=D5=FD=B3=A3=B9=D2=D4=D8=A1=A3=C1=ED= =CD=E2=C4=E3=B0=B2=D7=B0xenserver=B5=C4=CF=B5=CD=B3=C4=A3=B0=E5=C1=CB=C2=F0=A3= =BF > =B0=B2=D7=B0=CF=B5=CD=B3=C4=A3=B0=E5=D6=AE=BA=F3=A3=AC=D0=E8=D2=AAumount >=20 > 2014-04-13 15:05 GMT+08:00 andylauyang : >> =CA=D7=CF=C8=BA=DC=B8=D0=D0=BB=C4=FA=D4=DA=B0=D9=C3=A6=D6=AE=D6=D0=C4=DC=C4= =CD=D0=C4=B5=C4=BF=B4=CE=D2=B5=C4=CE=CA=CC=E2=A3=AC=B2=A2=B8=F8=D3=E8=CE=D2=BB= =D8=B8=B4=A3=AC=CE=D2=BD=AB=CE=D2=B5=C4=C5=E4=D6=C3=C7=E9=BF=F6=B8=F8=C4=FA=CF= =C8=BF=B4=BF=B4=A3=BA >> =CE=D2=D4=DAVMware WorkStation=CF=C2=B0=B2=D7=B0=B5=C4=C1=BD=B8=F6=D0=E9=BB= =FA=A3=AC=D2=BB=CC=A8=D7=F7cloudstack=B9=DC=C0=ED=A3=A8IP=CE=AA192.168.248.1= 0=A3=A9=A3=AC=C1=ED=D2=BB=CC=A8=D7=F7XenServer=A3=A8192.168.248.200=A3=A9=A3= =AC=D4=DA=B9=DC=C0=ED=C9=CF=B0=B2=D7=B0NFS=D7=F7=CE=AA=D2=BB=BC=B6=B4=E6=B4=A2= =BA=CD=B6=FE=BC=B6=B4=E6=B4=A2 >> selinux =C9=E8=D6=C3=CE=AApermissive , iptables =CE=D2=D6=B1=BD=D3=B9=D8=B1= =D5=C1=CB >> =CE=D2=B5=C4=D0=E9=C4=E2=BB=FA=C0=EF=B5=C4=CD=F8=B6=CE=CE=AA192.168.248.0= /24 >> =BD=F8=C8=EBWEB-UI=BA=F3=A3=AC=CA=D7=CF=C8=C9=E8=D6=C3=A1=B0=C8=AB=BE=D6=C9= =E8=D6=C3=A1=B1secstorage.allowed.internal.sites=D6=B5=CE=AA192.168.248.0/24= , management.network.cidr=CE=AA192.168.248.0/24 >> =BD=D3=CF=C2=C0=B4=CC=ED=BC=D3=C7=F8=D3=F2=A3=AC=CC=E1=B9=A9=B5=E3(=D4=A4= =C1=F4=B5=C4=CF=B5=CD=B3=CD=F8=B9=D8192.168.248.1=A3=AC=D4=A4=C1=F4=B5=C4=CF= =B5=CD=B3=CD=F8=C2=E7=D1=DA=C2=EB255.255.255.0=A3=AC=C6=F0=CA=BC=D4=A4=C1=F4= =CF=B5=CD=B3IP192.168.248.20=A3=AC=BD=E1=CA=F8=D4=A4=C1=F4=CF=B5=CD=B3IP192.= 168.248.30) >> =BD=D3=CF=C2=C0=B4=C9=E8=D6=C3=A1=B0=C0=B4=B1=F6=C1=F7=C1=BF=A1=B1=A3=A8=C0= =B4=B1=F6=CD=F8=B9=D8192.168.248.1=A3=AC=C0=B4=B1=F6=CD=F8=C2=E7=D1=DA=C2=EB= 255.255.255.0=A3=AC=C0=B4=B1=F6=C6=F0=CA=BCIP192.168.248.100=A3=AC=C0=B4=B1=F6= =BD=E1=CA=F8IP=A3=AC192.168.248.150=A3=A9 >> =C8=BB=BA=F3=CC=ED=BC=D3=BC=AF=C8=BA=BA=CD=D6=F7=BB=FA=A3=AC=D6=F7=B4=E6=B4= =A2=D3=C3=B5=C4NFS(=B7=FE=CE=F1=C6=F7=CE=AAcloudstack=B9=DC=C0=ED=B5=C4IP192= .168.248.10=A3=ACPath=CE=AA/pri)=A3=AC=B8=A8=D6=FA=B4=E6=B4=A2=D2=B2=CA=C7NFS= =A3=A8cloudstack=B9=DC=C0=ED=B5=C4IP192.168.248.10=A3=ACPath=CE=AA/sec=A3=A9= >> =D7=EE=BA=F3=C6=F4=B6=AF=A3=AC=D2=BB=C7=D0=CF=D4=CA=BE=D5=FD=B3=A3=CD=EA=B3= =C9=A3=AC=D7=EE=BA=F3Console Proxy VM=BA=CDSecondary Storage VM=C8=B4=CE=DE=B7= =A8=C6=F4=B6=AF >>=20 >> =D2=D4=C9=CF=CA=C7=CE=D2=B5=C4=C5=E4=D6=C3=C7=E9=BF=F6=A3=AC=D4=D9=B4=CE=D0= =BB=D0=BB=C4=FA >>=20 >>=20 >>=20 >>=20 >>=20 >> =B7=A2=BC=FE=C8=CB=A3=BA linuxbqj@gmail.com >> =B7=A2=CB=CD=CA=B1=BC=E4=A3=BA 2014-04-13 14:14 >> =CA=D5=BC=FE=C8=CB=A3=BA users-cn; andylauyang >> =D6=F7=CC=E2=A3=BA Re: cloudstack=D6=D0SSVM=C6=F4=B6=AF=B1=A8=B4=ED >> =BF=B4=C8=D5=D6=BE=A3=AC=CA=C7=C4=E3=B5=C4=B4=E6=B4=A2=D3=D0=CE=CA=CC=E2=A1= =A3 >>=20 >> =BD=A8=D2=E9=CB=B5=C3=F7=CF=C2=C4=E3=B5=C4=BB=F9=B1=BE=BB=B7=BE=B3=D0=C5=CF= =A2=A3=AC=D2=D4=BC=B0=D7=F6=B9=FD=C4=C4=D0=A9=B2=D9=D7=F7=C1=CB >>=20 >> 2014-04-13 12:37 GMT+08:00 andylauyang : >>> 2014-04-13 11:57:38,991 DEBUG [network.router.VirtualNetworkApplianceMan= agerImpl] (RouterStatusMonitor-1:null) Found 0 routers to update status. >>> 2014-04-13 11:57:38,992 DEBUG [network.router.VirtualNetworkApplianceMan= agerImpl] (RouterMonitor-1:null) Found 0 running routers. >>> 2014-04-13 11:57:38,995 DEBUG [network.router.VirtualNetworkApplianceMan= agerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status= . >>> 2014-04-13 11:57:39,129 DEBUG [network.router.VirtualNetworkApplianceMan= agerImpl] (RouterMonitor-1:null) Found 0 running routers. >>> 2014-04-13 11:57:39,132 DEBUG [network.router.VirtualNetworkApplianceMan= agerImpl] (RouterStatusMonitor-1:null) Found 0 routers to update status. >>> 2014-04-13 11:57:39,135 DEBUG [network.router.VirtualNetworkApplianceMan= agerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status= . >>> 2014-04-13 11:57:39,439 DEBUG [cloud.network.ExternalLoadBalancerUsageMa= nagerImpl] (ExternalNetworkMonitor-1:null) External devices stats collector i= s 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] (Direct= Agent-17:null) Ping from 1 >>> 2014-04-13 11:57:46,733 DEBUG [agent.manager.DirectAgentAttache] (Direct= Agent-5:null) Seq 1-1313341445: Executing request >>> 2014-04-13 11:57:46,932 DEBUG [agent.manager.DirectAgentAttache] (Direct= Agent-5:null) Seq 1-1313341445: Response Received: >>> 2014-04-13 11:57:46,933 DEBUG [agent.transport.Request] (DirectAgent-5:n= ull) Seq 1-1313341445: Processing: { Ans: , MgmtId: 52235347610, via: 1, Ve= r: v1, Flags: 10, [{"ClusterSyncAnswer":{"_clusterId":1,"_newStates":{},"_is= Executed":false,"result":true,"wait":0}}] } >>> 2014-04-13 11:57:54,237 DEBUG [cloud.server.StatsCollector] (StatsCollec= tor-3:null) VmStatsCollector is running... >>> 2014-04-13 11:57:56,137 DEBUG [cloud.server.StatsCollector] (StatsCollec= tor-2:null) HostStatsCollector is running... >>> 2014-04-13 11:57:56,151 DEBUG [agent.manager.DirectAgentAttache] (Direct= Agent-1:null) Seq 1-1313341463: Executing request >>> 2014-04-13 11:57:56,336 DEBUG [agent.manager.DirectAgentAttache] (Direct= Agent-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, V= er: v1, Flags: 10, { GetHostStatsAnswer } } >>> 2014-04-13 11:57:57,223 DEBUG [cloud.server.StatsCollector] (StatsCollec= tor-2:null) StorageCollector is running... >>> 2014-04-13 11:57:57,232 DEBUG [cloud.server.StatsCollector] (StatsCollec= tor-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] (Direct= Agent-18:null) Seq 1-1313341464: Executing request >>> 2014-04-13 11:57:57,542 DEBUG [agent.manager.DirectAgentAttache] (Direct= Agent-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, V= er: v1, Flags: 10, { GetStorageStatsAnswer } } >>> 2014-04-13 11:58:07,780 DEBUG [storage.secondary.SecondaryStorageManager= Impl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM >>> 2014-04-13 11:58:07,786 INFO [cloud.secstorage.PremiumSecondaryStorageM= anagerImpl] (secstorage-1:null) No running secondary storage vms found in da= tacenter id=3D1, starting one >>> 2014-04-13 11:58:07,795 INFO [storage.secondary.SecondaryStorageManager= Impl] (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.SecondaryStorageManager= Impl] (secstorage-1:null) Assign secondary storage vm from a newly started i= nstance for request from data center : 1 >>> 2014-04-13 11:58:07,806 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Found existing network configuration for offering [Network Offe= ring [6-Guest-DefaultSharedNetworkOfferingWithSGService]: Ntwk[204|Guest|6] >>> 2014-04-13 11:58:07,806 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Releasing lock for Acct[1-system] >>> 2014-04-13 11:58:07,816 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Found existing network configuration for offering [Network Offe= ring [3-Control-System-Control-Network]: Ntwk[202|Control|3] >>> 2014-04-13 11:58:07,816 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Releasing lock for Acct[1-system] >>> 2014-04-13 11:58:07,821 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Found existing network configuration for offering [Network Offe= ring [2-Management-System-Management-Network]: Ntwk[201|Management|2] >>> 2014-04-13 11:58:07,821 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Releasing lock for Acct[1-system] >>> 2014-04-13 11:58:07,824 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Found existing network configuration for offering [Network Offe= ring [4-Storage-System-Storage-Network]: Ntwk[203|Storage|4] >>> 2014-04-13 11:58:07,824 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Releasing lock for Acct[1-system] >>> 2014-04-13 11:58:07,830 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secs= torage-1:null) Allocating entries for VM: VM[SecondaryStorageVm|s-256-VM] >>> 2014-04-13 11:58:07,835 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secs= torage-1:null) Allocating nics for VM[SecondaryStorageVm|s-256-VM] >>> 2014-04-13 11:58:07,836 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in networ= k Ntwk[204|Guest|6] with requested profile NicProfile[0-0-null-null-null >>> 2014-04-13 11:58:07,856 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in networ= k Ntwk[202|Control|3] with requested profile null >>> 2014-04-13 11:58:07,864 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in networ= k Ntwk[201|Management|2] with requested profile null >>> 2014-04-13 11:58:07,901 DEBUG [cloud.network.NetworkManagerImpl] (secsto= rage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-256-VM] in networ= k Ntwk[203|Storage|4] with requested profile null >>> 2014-04-13 11:58:07,919 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secs= torage-1:null) Allocaing disks for VM[SecondaryStorageVm|s-256-VM] >>> 2014-04-13 11:58:07,924 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secs= torage-1:null) Allocation completed for VM: VM[SecondaryStorageVm|s-256-VM] >>> 2014-04-13 11:58:07,927 DEBUG [cloud.alert.SecondaryStorageVmAlertAdapte= r] (secstorage-1:null) received secondary storage vm alert >>> 2014-04-13 11:58:07,927 DEBUG [cloud.alert.SecondaryStorageVmAlertAdapte= r] (secstorage-1:null) New secondary storage vm created, zone: xenzone, secS= torageVm: s-256-VM, public IP: null, private IP: null >>> 2014-04-13 11:58:07,941 DEBUG [cloud.capacity.CapacityManagerImpl] (secs= torage-1:null) VM state transitted from :Stopped to Starting with event: Sta= rtRequestedvm's original host id: null new host id: null host id before stat= e transition: null >>> 2014-04-13 11:58:07,941 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secs= torage-1:null) Successfully transitioned to start state for VM[SecondaryStor= ageVm|s-256-VM] reservation id =3D 9ec9e9ea-1efa-444d-a5d1-b0f1bb6e0908 >>> 2014-04-13 11:58:07,945 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secs= torage-1:null) Trying to deploy VM, vm has dcId: 1 and podId: null >>> 2014-04-13 11:58:07,945 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secs= torage-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,clu= ster: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] (secsto= rage-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:= 1 >>> 2014-04-13 11:58:07,960 DEBUG [allocator.impl.FirstFitAllocator] (secsto= rage-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for a= llocation: [Host[-1-Routing]] >>> 2014-04-13 11:58:07,969 DEBUG [allocator.impl.FirstFitAllocator] (secsto= rage-1:FirstFitRoutingAllocator) Found 1 hosts for allocation after prioriti= zation: [Host[-1-Routing]] >>> 2014-04-13 11:58:07,970 DEBUG [allocator.impl.FirstFitAllocator] (secsto= rage-1:FirstFitRoutingAllocator) Looking for speed=3D500Mhz, Ram=3D256 >>> 2014-04-13 11:58:07,973 DEBUG [cloud.capacity.CapacityManagerImpl] (secs= torage-1:FirstFitRoutingAllocator) Checking if host: 1 has enough capacity f= or requested CPU: 500 and requested RAM: 268435456 , cpuOverprovisioningFact= or: 1.0 >>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secs= torage-1:FirstFitRoutingAllocator) Hosts's actual total CPU: 2493 and CPU af= ter applying overprovisioning: 2493 >>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secs= torage-1:FirstFitRoutingAllocator) Free CPU: 2493 , Requested CPU: 500 >>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secs= torage-1:FirstFitRoutingAllocator) Free RAM: 1671630912 , Requested RAM: 268= 435456 >>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secs= torage-1:FirstFitRoutingAllocator) Host has enough CPU and RAM available >>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secs= torage-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1, used: 0= , reserved: 0, actual total: 2493, total with overprovisioning: 2493; reques= ted cpu:500,alloc_from_last_host?:false ,considerReservedCapacity?: true >>> 2014-04-13 11:58:07,976 DEBUG [cloud.capacity.CapacityManagerImpl] (secs= torage-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1, used: 0= , reserved: 0, total: 1671630912; requested mem: 268435456,alloc_from_last_h= ost?:false ,considerReservedCapacity?: true >>> 2014-04-13 11:58:07,976 DEBUG [allocator.impl.FirstFitAllocator] (secsto= rage-1:FirstFitRoutingAllocator) Found a suitable host, adding to list: 1 >>> 2014-04-13 11:58:07,976 DEBUG [allocator.impl.FirstFitAllocator] (secsto= rage-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.LocalStoragePoolAllocat= or] (secstorage-1:null) LocalStoragePoolAllocator trying to find storage poo= l to fit the vm >>> 2014-04-13 11:58:07,979 DEBUG [storage.allocator.FirstFitStoragePoolAllo= cator] (secstorage-1:null) Looking for pools in dc: 1 pod:1 cluster:1 >>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.FirstFitStoragePoolAllo= cator] (secstorage-1:null) FirstFitStoragePoolAllocator has 1 pools to check= for allocation >>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (secstorage-1:null) Checking if storage pool is suitable, name: prima= ry ,poolId: 215 >>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (secstorage-1:null) Is localStorageAllocationNeeded? true >>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (secstorage-1:null) Is storage pool shared? true >>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (secstorage-1:null) StoragePool is not of correct type, skipping this= pool >>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.FirstFitStoragePoolAllo= cator] (secstorage-1:null) FirstFitStoragePoolAllocator returning 0 suitable= storage pools >>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.LocalStoragePoolAllocat= or] (secstorage-1:null) LocalStoragePoolAllocator returning 0 suitable stora= ge pools >>> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.LocalStoragePoolAllocat= or] (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=3D256|ROOT] under cl= uster: 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 an= y clusters, returning. >>> 2014-04-13 11:58:07,987 DEBUG [cloud.capacity.CapacityManagerImpl] (secs= torage-1:null) VM state transitted from :Starting to Stopped with event: Ope= rationFailedvm's original host id: null new host id: null host id before sta= te transition: null >>> 2014-04-13 11:58:07,988 WARN [storage.secondary.SecondaryStorageManager= Impl] (secstorage-1:null) Exception while trying to start secondary storage v= m >>> com.cloud.exception.InsufficientServerCapacityException: Unable to creat= e a deployment for VM[SecondaryStorageVm|s-256-VM]Scope=3Dinterface com.clou= d.dc.DataCenter; id=3D1 >>> at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineMan= agerImpl.java:728) >>> at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImp= l.java:471) >>> at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImp= l.java:464) >>> at com.cloud.storage.secondary.SecondaryStorageManagerImpl.startSecStora= geVm(SecondaryStorageManagerImpl.java:269) >>> at com.cloud.storage.secondary.SecondaryStorageManagerImpl.allocCapacity= (SecondaryStorageManagerImpl.java:696) >>> at com.cloud.storage.secondary.SecondaryStorageManagerImpl.expandPool(Se= condaryStorageManagerImpl.java:1307) >>> at com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(Prem= iumSecondaryStorageManagerImpl.java:121) >>> at com.cloud.secstorage.PremiumSecondaryStorageManagerImpl.scanPool(Prem= iumSecondaryStorageManagerImpl.java:52) >>> at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:10= 4) >>> 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:47= 1) >>> 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.SecondaryStorageManager= Impl] (secstorage-1:null) Unable to start secondary storage vm for standby c= apacity, secStorageVm vm Id : 256, will recycle it and start a new one >>> 2014-04-13 11:58:07,989 INFO [cloud.secstorage.PremiumSecondaryStorageM= anagerImpl] (secstorage-1:null) Primary secondary storage is not even starte= d, wait until next turn >>> 2014-04-13 11:58:08,297 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImp= l] (consoleproxy-1:null) Zone 1 is ready to launch console proxy >>> 2014-04-13 11:58:08,297 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImp= l] (consoleproxy-1:null) Expand console proxy standby capacity for zone xenz= one >>> 2014-04-13 11:58:08,300 INFO [cloud.consoleproxy.ConsoleProxyManagerImp= l] (consoleproxy-1:null) Found a stopped console proxy, bring it up to runni= ng pool. proxy vm id : 197 >>> 2014-04-13 11:58:08,313 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:null) VM state transitted from :Stopped to Starting with event: S= tartRequestedvm's original host id: null new host id: null host id before st= ate transition: null >>> 2014-04-13 11:58:08,313 DEBUG [cloud.vm.VirtualMachineManagerImpl] (cons= oleproxy-1:null) Successfully transitioned to start state for VM[ConsoleProx= y|v-197-VM] reservation id =3D ed8bc4e4-f9f2-4ea6-a25f-fc5e4815d1db >>> 2014-04-13 11:58:08,328 DEBUG [cloud.vm.VirtualMachineManagerImpl] (cons= oleproxy-1:null) Trying to deploy VM, vm has dcId: 1 and podId: null >>> 2014-04-13 11:58:08,328 DEBUG [cloud.vm.VirtualMachineManagerImpl] (cons= oleproxy-1:null) Deploy avoids pods: null, clusters: null, hosts: null >>> 2014-04-13 11:58:08,332 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) DeploymentPlanner allocation algorithm: random >>> 2014-04-13 11:58:08,333 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) Trying to allocate a host and storage pools from dc:1, pod:null,c= luster:null, requested cpu: 500, requested ram: 1073741824 >>> 2014-04-13 11:58:08,333 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) Is ROOT volume READY (pool already allocated)?: No >>> 2014-04-13 11:58:08,333 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) Searching all possible resources under this Zone: 1 >>> 2014-04-13 11:58:08,336 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) Listing clusters in order of aggregate capacity, that have (atlea= st one host with) enough CPU and RAM capacity under this Zone: 1 >>> 2014-04-13 11:58:08,337 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) CPUOverprovisioningFactor considered: 1.0 >>> 2014-04-13 11:58:08,347 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) Checking resources in Cluster: 1 under Pod: 1 >>> 2014-04-13 11:58:08,347 DEBUG [allocator.impl.FirstFitAllocator] (consol= eproxy-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluste= r:1 >>> 2014-04-13 11:58:08,349 DEBUG [allocator.impl.FirstFitAllocator] (consol= eproxy-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check fo= r allocation: [Host[-1-Routing]] >>> 2014-04-13 11:58:08,351 DEBUG [allocator.impl.FirstFitAllocator] (consol= eproxy-1:FirstFitRoutingAllocator) Found 1 hosts for allocation after priori= tization: [Host[-1-Routing]] >>> 2014-04-13 11:58:08,351 DEBUG [allocator.impl.FirstFitAllocator] (consol= eproxy-1:FirstFitRoutingAllocator) Looking for speed=3D500Mhz, Ram=3D1024 >>> 2014-04-13 11:58:08,355 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:FirstFitRoutingAllocator) Checking if host: 1 has enough capacity= for requested CPU: 500 and requested RAM: 1073741824 , cpuOverprovisioningFa= ctor: 1.0 >>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:FirstFitRoutingAllocator) Hosts's actual total CPU: 2493 and CPU a= fter applying overprovisioning: 2493 >>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:FirstFitRoutingAllocator) Free CPU: 2493 , Requested CPU: 500 >>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:FirstFitRoutingAllocator) Free RAM: 1671630912 , Requested RAM: 1= 073741824 >>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:FirstFitRoutingAllocator) Host has enough CPU and RAM available >>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1, used= : 0, reserved: 0, actual total: 2493, total with overprovisioning: 2493; req= uested cpu:500,alloc_from_last_host?:false ,considerReservedCapacity?: true >>> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1, used= : 0, reserved: 0, total: 1671630912; requested mem: 1073741824,alloc_from_la= st_host?:false ,considerReservedCapacity?: true >>> 2014-04-13 11:58:08,358 DEBUG [allocator.impl.FirstFitAllocator] (consol= eproxy-1:FirstFitRoutingAllocator) Found a suitable host, adding to list: 1 >>> 2014-04-13 11:58:08,358 DEBUG [allocator.impl.FirstFitAllocator] (consol= eproxy-1:FirstFitRoutingAllocator) Host Allocator returning 1 suitable hosts= >>> 2014-04-13 11:58:08,365 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) Checking suitable pools for volume (Id, Type): (197,ROOT) >>> 2014-04-13 11:58:08,365 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) We need to allocate new storagepool for this volume >>> 2014-04-13 11:58:08,366 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) Calling StoragePoolAllocators to find suitable pools >>> 2014-04-13 11:58:08,368 DEBUG [storage.allocator.LocalStoragePoolAllocat= or] (consoleproxy-1:null) LocalStoragePoolAllocator trying to find storage p= ool to fit the vm >>> 2014-04-13 11:58:08,368 DEBUG [storage.allocator.FirstFitStoragePoolAllo= cator] (consoleproxy-1:null) Looking for pools in dc: 1 pod:1 cluster:1 >>> 2014-04-13 11:58:08,370 DEBUG [storage.allocator.FirstFitStoragePoolAllo= cator] (consoleproxy-1:null) FirstFitStoragePoolAllocator has 1 pools to che= ck for allocation >>> 2014-04-13 11:58:08,370 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (consoleproxy-1:null) Checking if storage pool is suitable, name: pri= mary ,poolId: 215 >>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (consoleproxy-1:null) Is localStorageAllocationNeeded? true >>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (consoleproxy-1:null) Is storage pool shared? true >>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (consoleproxy-1:null) StoragePool is not of correct type, skipping th= is pool >>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.FirstFitStoragePoolAllo= cator] (consoleproxy-1:null) FirstFitStoragePoolAllocator returning 0 suitab= le storage pools >>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.LocalStoragePoolAllocat= or] (consoleproxy-1:null) LocalStoragePoolAllocator returning 0 suitable sto= rage pools >>> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.LocalStoragePoolAllocat= or] (consoleproxy-1:null) Unable to find storage pool to fit the vm >>> 2014-04-13 11:58:08,371 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) No suitable pools found for volume: Vol[197|vm=3D197|ROOT] under c= luster: 1 >>> 2014-04-13 11:58:08,371 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) No suitable pools found >>> 2014-04-13 11:58:08,371 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) No suitable storagePools found under this Cluster: 1 >>> 2014-04-13 11:58:08,372 DEBUG [cloud.deploy.FirstFitPlanner] (consolepro= xy-1:null) Could not find suitable Deployment Destination for this VM under a= ny clusters, returning. >>> 2014-04-13 11:58:08,379 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:null) VM state transitted from :Starting to Stopped with event: O= perationFailedvm's original host id: null new host id: null host id before s= tate transition: null >>> 2014-04-13 11:58:08,381 WARN [cloud.consoleproxy.ConsoleProxyManagerImp= l] (consoleproxy-1:null) Exception while trying to start console proxy >>> com.cloud.exception.InsufficientServerCapacityException: Unable to creat= e a deployment for VM[ConsoleProxy|v-197-VM]Scope=3Dinterface com.cloud.dc.D= ataCenter; id=3D1 >>> at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineMan= agerImpl.java:728) >>> at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImp= l.java:471) >>> at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImp= l.java:464) >>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.startProxy(ConsoleProx= yManagerImpl.java:632) >>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.allocCapacity(ConsoleP= roxyManagerImpl.java:1166) >>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProx= yManagerImpl.java:1985) >>> at com.cloud.consoleproxy.ConsoleProxyManagerImpl.expandPool(ConsoleProx= yManagerImpl.java:175) >>> at com.cloud.vm.SystemVmLoadScanner.loadScan(SystemVmLoadScanner.java:11= 1) >>> 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:47= 1) >>> 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) >>=20 >>=20 >>=20 >> -- >> =B0=D7=C7=E5=BD=DC (Born Bai) >>=20 >> =B1=B1=BE=A9=BF=AA=D4=B4=D4=B8=BE=B0=D0=C5=CF=A2=BC=BC=CA=F5=D3=D0=CF=DE=B9= =AB=CB=BE >>=20 >> Mail: linuxbqj@gmail.com >=20 >=20 >=20 > --=20 > =B0=D7=C7=E5=BD=DC (Born Bai) >=20 > =B1=B1=BE=A9=BF=AA=D4=B4=D4=B8=BE=B0=D0=C5=CF=A2=BC=BC=CA=F5=D3=D0=CF=DE=B9= =AB=CB=BE >=20 > Mail: linuxbqj@gmail.com