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 006DF11333 for ; Sun, 13 Apr 2014 07:16:56 +0000 (UTC) Received: (qmail 60119 invoked by uid 500); 13 Apr 2014 07:16:55 -0000 Delivered-To: apmail-cloudstack-users-cn-archive@cloudstack.apache.org Received: (qmail 59910 invoked by uid 500); 13 Apr 2014 07:16:52 -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 59141 invoked by uid 99); 13 Apr 2014 07:16:51 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 13 Apr 2014 07:16:51 +0000 X-ASF-Spam-Status: No, hits=-0.7 required=5.0 tests=RCVD_IN_DNSWL_LOW,SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: domain of linuxbqj@gmail.com designates 209.85.212.170 as permitted sender) Received: from [209.85.212.170] (HELO mail-wi0-f170.google.com) (209.85.212.170) by apache.org (qpsmtpd/0.29) with ESMTP; Sun, 13 Apr 2014 07:16:45 +0000 Received: by mail-wi0-f170.google.com with SMTP id bs8so4165180wib.1 for ; Sun, 13 Apr 2014 00:16:23 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=20120113; h=mime-version:in-reply-to:references:from:date:message-id:subject:to :content-type:content-transfer-encoding; bh=ndEPL6vFskkpUQ9WczGPjpcb1cT6JUONLOE2+r0s3t0=; b=QVyomHsp53SGBO8uQrjVp0tXJdMCIu5Pm1BqnjlmGgoR0iNxPBZy8HYpspBunQMYB2 4D1b6uCjtFWttrP9uXRdoyx/cfmt9IHJYJHfu+Z1sCkwdW2mfyvHhfXShj5nY3zXPRrV Jj4eGjqjMYKkEAy7gDEPql+3aMLMupfm51GfT1Sy/+MorVrRJGpIbBT9wa6csVzS7QZL MDMrEVQvjnJQhc98dr2QYilS0MVZ7K7fZD9jP1ry8qP8tQFtvomPbsNUgsZGF0Kub++j WvK6VKd5JI7od+iNm3af1kbabaitits3ZNXlH6oNwOHD/dx32aECfKy0nE7/+rCuif5g ADZA== X-Received: by 10.194.205.161 with SMTP id lh1mr11911456wjc.40.1397373383439; Sun, 13 Apr 2014 00:16:23 -0700 (PDT) MIME-Version: 1.0 Received: by 10.194.219.68 with HTTP; Sun, 13 Apr 2014 00:16:03 -0700 (PDT) In-Reply-To: <2014041315053324372237@126.com> References: <201404131237449844607@126.com> <2014041315053324372237@126.com> From: "linuxbqj@gmail.com" Date: Sun, 13 Apr 2014 15:16:03 +0800 Message-ID: Subject: =?UTF-8?B?UmU6IFJlOiBjbG91ZHN0YWNr5LitU1NWTeWQr+WKqOaKpemUmQ==?= To: users-cn@cloudstack.apache.org, andylauyang Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: quoted-printable X-Virus-Checked: Checked by ClamAV on apache.org =E4=BD=A0=E6=A3=80=E6=9F=A5=E4=B8=8BNFS=E8=83=BD=E5=90=A6=E6=AD=A3=E5=B8=B8= =E6=8C=82=E8=BD=BD=E3=80=82=E5=8F=A6=E5=A4=96=E4=BD=A0=E5=AE=89=E8=A3=85xen= server=E7=9A=84=E7=B3=BB=E7=BB=9F=E6=A8=A1=E6=9D=BF=E4=BA=86=E5=90=97=EF=BC= =9F =E5=AE=89=E8=A3=85=E7=B3=BB=E7=BB=9F=E6=A8=A1=E6=9D=BF=E4=B9=8B=E5=90=8E=EF= =BC=8C=E9=9C=80=E8=A6=81umount 2014-04-13 15:05 GMT+08:00 andylauyang : > =E9=A6=96=E5=85=88=E5=BE=88=E6=84=9F=E8=B0=A2=E6=82=A8=E5=9C=A8=E7=99=BE= =E5=BF=99=E4=B9=8B=E4=B8=AD=E8=83=BD=E8=80=90=E5=BF=83=E7=9A=84=E7=9C=8B=E6= =88=91=E7=9A=84=E9=97=AE=E9=A2=98=EF=BC=8C=E5=B9=B6=E7=BB=99=E4=BA=88=E6=88= =91=E5=9B=9E=E5=A4=8D=EF=BC=8C=E6=88=91=E5=B0=86=E6=88=91=E7=9A=84=E9=85=8D= =E7=BD=AE=E6=83=85=E5=86=B5=E7=BB=99=E6=82=A8=E5=85=88=E7=9C=8B=E7=9C=8B=EF= =BC=9A > =E6=88=91=E5=9C=A8VMware WorkStation=E4=B8=8B=E5=AE=89=E8=A3=85=E7=9A=84= =E4=B8=A4=E4=B8=AA=E8=99=9A=E6=9C=BA=EF=BC=8C=E4=B8=80=E5=8F=B0=E4=BD=9Cclo= udstack=E7=AE=A1=E7=90=86=EF=BC=88IP=E4=B8=BA192.168.248.10=EF=BC=89=EF=BC= =8C=E5=8F=A6=E4=B8=80=E5=8F=B0=E4=BD=9CXenServer=EF=BC=88192.168.248.200=EF= =BC=89=EF=BC=8C=E5=9C=A8=E7=AE=A1=E7=90=86=E4=B8=8A=E5=AE=89=E8=A3=85NFS=E4= =BD=9C=E4=B8=BA=E4=B8=80=E7=BA=A7=E5=AD=98=E5=82=A8=E5=92=8C=E4=BA=8C=E7=BA= =A7=E5=AD=98=E5=82=A8 > selinux =E8=AE=BE=E7=BD=AE=E4=B8=BApermissive , iptables =E6=88=91=E7=9B= =B4=E6=8E=A5=E5=85=B3=E9=97=AD=E4=BA=86 > =E6=88=91=E7=9A=84=E8=99=9A=E6=8B=9F=E6=9C=BA=E9=87=8C=E7=9A=84=E7=BD=91= =E6=AE=B5=E4=B8=BA192.168.248.0/24 > =E8=BF=9B=E5=85=A5WEB-UI=E5=90=8E=EF=BC=8C=E9=A6=96=E5=85=88=E8=AE=BE=E7= =BD=AE=E2=80=9C=E5=85=A8=E5=B1=80=E8=AE=BE=E7=BD=AE=E2=80=9Dsecstorage.allo= wed.internal.sites=E5=80=BC=E4=B8=BA192.168.248.0/24 , management.network.= cidr=E4=B8=BA192.168.248.0/24 > =E6=8E=A5=E4=B8=8B=E6=9D=A5=E6=B7=BB=E5=8A=A0=E5=8C=BA=E5=9F=9F=EF=BC=8C= =E6=8F=90=E4=BE=9B=E7=82=B9(=E9=A2=84=E7=95=99=E7=9A=84=E7=B3=BB=E7=BB=9F= =E7=BD=91=E5=85=B3192.168.248.1=EF=BC=8C=E9=A2=84=E7=95=99=E7=9A=84=E7=B3= =BB=E7=BB=9F=E7=BD=91=E7=BB=9C=E6=8E=A9=E7=A0=81255.255.255.0=EF=BC=8C=E8= =B5=B7=E5=A7=8B=E9=A2=84=E7=95=99=E7=B3=BB=E7=BB=9FIP192.168.248.20=EF=BC= =8C=E7=BB=93=E6=9D=9F=E9=A2=84=E7=95=99=E7=B3=BB=E7=BB=9FIP192.168.248.30) > =E6=8E=A5=E4=B8=8B=E6=9D=A5=E8=AE=BE=E7=BD=AE=E2=80=9C=E6=9D=A5=E5=AE=BE= =E6=B5=81=E9=87=8F=E2=80=9D=EF=BC=88=E6=9D=A5=E5=AE=BE=E7=BD=91=E5=85=B3192= .168.248.1=EF=BC=8C=E6=9D=A5=E5=AE=BE=E7=BD=91=E7=BB=9C=E6=8E=A9=E7=A0=8125= 5.255.255.0=EF=BC=8C=E6=9D=A5=E5=AE=BE=E8=B5=B7=E5=A7=8BIP192.168.248.100= =EF=BC=8C=E6=9D=A5=E5=AE=BE=E7=BB=93=E6=9D=9FIP=EF=BC=8C192.168.248.150=EF= =BC=89 > =E7=84=B6=E5=90=8E=E6=B7=BB=E5=8A=A0=E9=9B=86=E7=BE=A4=E5=92=8C=E4=B8=BB= =E6=9C=BA=EF=BC=8C=E4=B8=BB=E5=AD=98=E5=82=A8=E7=94=A8=E7=9A=84NFS(=E6=9C= =8D=E5=8A=A1=E5=99=A8=E4=B8=BAcloudstack=E7=AE=A1=E7=90=86=E7=9A=84IP192.16= 8.248.10=EF=BC=8CPath=E4=B8=BA/pri)=EF=BC=8C=E8=BE=85=E5=8A=A9=E5=AD=98=E5= =82=A8=E4=B9=9F=E6=98=AFNFS=EF=BC=88cloudstack=E7=AE=A1=E7=90=86=E7=9A=84IP= 192.168.248.10=EF=BC=8CPath=E4=B8=BA/sec=EF=BC=89 > =E6=9C=80=E5=90=8E=E5=90=AF=E5=8A=A8=EF=BC=8C=E4=B8=80=E5=88=87=E6=98=BE= =E7=A4=BA=E6=AD=A3=E5=B8=B8=E5=AE=8C=E6=88=90=EF=BC=8C=E6=9C=80=E5=90=8ECon= sole Proxy VM=E5=92=8CSecondary Storage VM=E5=8D=B4=E6=97=A0=E6=B3=95=E5=90= =AF=E5=8A=A8 > > =E4=BB=A5=E4=B8=8A=E6=98=AF=E6=88=91=E7=9A=84=E9=85=8D=E7=BD=AE=E6=83=85= =E5=86=B5=EF=BC=8C=E5=86=8D=E6=AC=A1=E8=B0=A2=E8=B0=A2=E6=82=A8 > > > > > > =E5=8F=91=E4=BB=B6=E4=BA=BA=EF=BC=9A linuxbqj@gmail.com > =E5=8F=91=E9=80=81=E6=97=B6=E9=97=B4=EF=BC=9A 2014-04-13 14:14 > =E6=94=B6=E4=BB=B6=E4=BA=BA=EF=BC=9A users-cn; andylauyang > =E4=B8=BB=E9=A2=98=EF=BC=9A Re: cloudstack=E4=B8=ADSSVM=E5=90=AF=E5=8A=A8= =E6=8A=A5=E9=94=99 > =E7=9C=8B=E6=97=A5=E5=BF=97=EF=BC=8C=E6=98=AF=E4=BD=A0=E7=9A=84=E5=AD=98= =E5=82=A8=E6=9C=89=E9=97=AE=E9=A2=98=E3=80=82 > > =E5=BB=BA=E8=AE=AE=E8=AF=B4=E6=98=8E=E4=B8=8B=E4=BD=A0=E7=9A=84=E5=9F=BA= =E6=9C=AC=E7=8E=AF=E5=A2=83=E4=BF=A1=E6=81=AF=EF=BC=8C=E4=BB=A5=E5=8F=8A=E5= =81=9A=E8=BF=87=E5=93=AA=E4=BA=9B=E6=93=8D=E4=BD=9C=E4=BA=86 > > 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 statu= s. >> 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 statu= s. >> 2014-04-13 11:57:39,439 DEBUG [cloud.network.ExternalLoadBalancerUsageMa= nagerImpl] (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-1= 3 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:3= 9 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, V= er: v1, Flags: 10, [{"ClusterSyncAnswer":{"_clusterId":1,"_newStates":{},"_= isExecuted":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, = Ver: 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, = Ver: 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 d= atacenter 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, nee= d 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 = instance 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 Off= ering [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 Off= ering [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 Off= ering [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 Off= ering [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 netwo= rk 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 netwo= rk 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 netwo= rk 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 netwo= rk 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, sec= StorageVm: 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: St= artRequestedvm's original host id: null new host id: null host id before st= ate transition: null >> 2014-04-13 11:58:07,941 DEBUG [cloud.vm.VirtualMachineManagerImpl] (secs= torage-1:null) Successfully transitioned to start state for VM[SecondarySto= rageVm|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,cl= uster: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 (atleas= t 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= allocation: [Host[-1-Routing]] >> 2014-04-13 11:58:07,969 DEBUG [allocator.impl.FirstFitAllocator] (secsto= rage-1:FirstFitRoutingAllocator) Found 1 hosts for allocation after priorit= ization: [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 = for requested CPU: 500 and requested RAM: 268435456 , cpuOverprovisioningFa= ctor: 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 a= fter 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: 26= 8435456 >> 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; req= uested 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_las= t_host?: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 po= ol 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 chec= k for allocation >> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (secstorage-1:null) Checking if storage pool is suitable, name: prim= ary ,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 thi= s pool >> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.FirstFitStoragePoolAllo= cator] (secstorage-1:null) FirstFitStoragePoolAllocator returning 0 suitabl= e storage pools >> 2014-04-13 11:58:07,981 DEBUG [storage.allocator.LocalStoragePoolAllocat= or] (secstorage-1:null) LocalStoragePoolAllocator returning 0 suitable stor= age 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 c= luster: 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 a= ny 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: Op= erationFailedvm's original host id: null new host id: null host id before s= tate transition: null >> 2014-04-13 11:58:07,988 WARN [storage.secondary.SecondaryStorageManager= Impl] (secstorage-1:null) Exception while trying to start secondary storage= vm >> com.cloud.exception.InsufficientServerCapacityException: Unable to creat= e a deployment for VM[SecondaryStorageVm|s-256-VM]Scope=3Dinterface com.clo= ud.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 = capacity, 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 start= ed, 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 xen= zone >> 2014-04-13 11:58:08,300 INFO [cloud.consoleproxy.ConsoleProxyManagerImp= l] (consoleproxy-1:null) Found a stopped console proxy, bring it up to runn= ing 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: = 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] (cons= oleproxy-1:null) Successfully transitioned to start state for VM[ConsolePro= xy|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,= cluster: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 (atle= ast 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 clust= er:1 >> 2014-04-13 11:58:08,349 DEBUG [allocator.impl.FirstFitAllocator] (consol= eproxy-1:FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check f= or 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 prior= itization: [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 capacit= y for requested CPU: 500 and requested RAM: 1073741824 , cpuOverprovisionin= gFactor: 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= after 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: = 1073741824 >> 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, use= d: 0, reserved: 0, actual total: 2493, total with overprovisioning: 2493; r= equested cpu:500,alloc_from_last_host?:false ,considerReservedCapacity?: tr= ue >> 2014-04-13 11:58:08,358 DEBUG [cloud.capacity.CapacityManagerImpl] (cons= oleproxy-1:FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1, use= d: 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] (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 host= s >> 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 = pool 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 ch= eck for allocation >> 2014-04-13 11:58:08,370 DEBUG [storage.allocator.AbstractStoragePoolAllo= cator] (consoleproxy-1:null) Checking if storage pool is suitable, name: pr= imary ,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 t= his pool >> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.FirstFitStoragePoolAllo= cator] (consoleproxy-1:null) FirstFitStoragePoolAllocator returning 0 suita= ble storage pools >> 2014-04-13 11:58:08,371 DEBUG [storage.allocator.LocalStoragePoolAllocat= or] (consoleproxy-1:null) LocalStoragePoolAllocator returning 0 suitable st= orage 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= cluster: 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= any 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: = 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.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.= 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.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) > > > > -- > =E7=99=BD=E6=B8=85=E6=9D=B0 (Born Bai) > > =E5=8C=97=E4=BA=AC=E5=BC=80=E6=BA=90=E6=84=BF=E6=99=AF=E4=BF=A1=E6=81=AF= =E6=8A=80=E6=9C=AF=E6=9C=89=E9=99=90=E5=85=AC=E5=8F=B8 > > Mail: linuxbqj@gmail.com --=20 =E7=99=BD=E6=B8=85=E6=9D=B0 (Born Bai) =E5=8C=97=E4=BA=AC=E5=BC=80=E6=BA=90=E6=84=BF=E6=99=AF=E4=BF=A1=E6=81=AF=E6= =8A=80=E6=9C=AF=E6=9C=89=E9=99=90=E5=85=AC=E5=8F=B8 Mail: linuxbqj@gmail.com