From users-cn-return-4131-apmail-cloudstack-users-cn-archive=cloudstack.apache.org@cloudstack.apache.org Thu Nov 27 03:46:44 2014 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 39AB110619 for ; Thu, 27 Nov 2014 03:46:44 +0000 (UTC) Received: (qmail 20512 invoked by uid 500); 27 Nov 2014 03:46:43 -0000 Delivered-To: apmail-cloudstack-users-cn-archive@cloudstack.apache.org Received: (qmail 20468 invoked by uid 500); 27 Nov 2014 03:46:43 -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 20455 invoked by uid 99); 27 Nov 2014 03:46:43 -0000 Received: from nike.apache.org (HELO nike.apache.org) (192.87.106.230) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 27 Nov 2014 03:46:43 +0000 X-ASF-Spam-Status: No, hits=2.7 required=5.0 tests=MIME_QP_LONG_LINE,RCVD_IN_PSBL,SPF_PASS X-Spam-Check-By: apache.org Received-SPF: pass (nike.apache.org: domain of handeng@126.com designates 123.125.50.110 as permitted sender) Received: from [123.125.50.110] (HELO m50-110.126.com) (123.125.50.110) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 27 Nov 2014 03:46:17 +0000 DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=126.com; s=s110527; h=Subject:From:Message-Id:Date:Mime-Version; bh=oJYCC WQb7w4YcV1T1gh/xpbtargMSkDvkMnXERS95SU=; b=CcdrEjTrf2uX5Xdxc//h8 6xjMiW2XRo5+A695NXtj7m9HtLwtQs4+TTDT77bNQ+kb9CUvPR830Tqob9nEd+BG AdHjaQKolEFfz2wC660vcCsMf/E0P6BnHfOV+WerelMiUO7jNq6TCrEugXahaQT0 T+6iTcZrDjPUNhG477wwIg= Received: from [172.30.229.168] (unknown [139.214.252.20]) by smtp4 (Coremail) with SMTP id jdKowABXvaVknnZUYQVXAg--.1912S2; Thu, 27 Nov 2014 11:45:42 +0800 (CST) Subject: =?GB2312?B?UmU6INDpxOK7+sb0tq+yu8bwwLS1xM7KzOI=?= References: <004f01d009e8$0afa8fc0$20efaf40$@com> From: "handeng@126" Content-Type: text/plain; charset=gb2312 X-Mailer: iPhone Mail (12B411) In-Reply-To: <004f01d009e8$0afa8fc0$20efaf40$@com> Message-Id: Date: Thu, 27 Nov 2014 11:45:35 +0800 To: "users-cn@cloudstack.apache.org" Content-Transfer-Encoding: quoted-printable Mime-Version: 1.0 (1.0) X-CM-TRANSID:jdKowABXvaVknnZUYQVXAg--.1912S2 X-Coremail-Antispam: 1Uf129KBjvAXoW3CFyktw43CF15Ww4DXFy7Wrg_yoW8Zryxto W3Xr13Kw1v9398Z3W8tF4kuF15uFWUWw48tFW5GrWUCayIqrZ7KayUur43Gr9xJw4rX347 Ca1UZasrCFWxtas7n29KB7ZKAUJUUUUU529EdanIXcx71UUUUU7v73VFW2AGmfu7bjvjm3 AaLaJ3UbIYCTnIWIevJa73UjIFyTuYvj4Rsa9aDUUUU X-Originating-IP: [139.214.252.20] X-CM-SenderInfo: xkdqvvlqj6ij2wof0z/1tbiWA1REU3AYXUI3wAAsb X-Virus-Checked: Checked by ClamAV on apache.org =B0=D1=B6=FE=BC=B6=B4=E6=B4=A2=D6=D8=D0=C2=B9=D2=C9=CF > On Nov 27, 2014, at 10:15 AM, xusz wrote: >=20 >=20 >=20 >=20 >=20 >=20 >=20 > HI ALL=20 >=20 > =C9=CF=B4=CE=D4=DA=C6=BD=CC=A8=C9=CF=C9=BE=B3=FD=C1=CB=B6=FE=BC=B6=B4=E6=B4= =A2=A3=AC=B7=A2=CF=D6=C4=A3=B0=E5=C0=EF=C3=E6=D0=E9=C4=E2=BB=FA=B5=C4=BE=B5=CF= =F1=B6=BC=B1=E4=B3=C9=B4=F2X=D7=B4=CC=AC=A3=AC=CF=D6=D4=DA=C6=F4=B6=AF > =D0=E9=C4=E2=BB=FA=B5=C4=CA=B1=BA=F2=B6=BC=B2=BB=C4=DC=C6=F0=C0=B4=A3=AC=C7= =F3=D6=FA=B4=F3=BC=D2=C5=C5=B2=E9=CA=C7=CA=B2=C3=B4=CE=CA=CC=E2=A1=A3=D0=BB=D0= =BB >=20 >=20 >=20 >=20 >=20 > 14-11-27 10:11:33,089 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (HA-Worker-1:work-14354) Deploy avoids pods: null, clusters: null, hosts: > null >=20 > 2014-11-27 10:11:33,091 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (HA-Worker-1:work-14354) Root volume is ready, need to place VM in volume'= s > cluster >=20 > 2014-11-27 10:11:33,091 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (HA-Worker-1:work-14354) Vol[7333|vm=3D7162|ROOT] is READY, changing > deployment plan to use this pool's dcId: 1 , podId: 1 , and clusterId: 10 >=20 > 2014-11-27 10:11:33,091 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) DeploymentPlanner allocation algorithm: random >=20 > 2014-11-27 10:11:33,091 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Trying to allocate a host and storage pools from > dc:1, pod:1,cluster:10, requested cpu: 2000, requested ram: 2147483648 >=20 > 2014-11-27 10:11:33,091 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Is ROOT volume READY (pool already allocated)?: Y= es >=20 > 2014-11-27 10:11:33,091 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) This VM has last host_id specified, trying to > choose the same host: 89 >=20 > 2014-11-27 10:11:33,094 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Checking if host: 89 has enough capacity for > requested CPU: 2000 and requested RAM: 2147483648 , > cpuOverprovisioningFactor: 2.0 >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Hosts's actual total CPU: 36256 and CPU after > applying overprovisioning: 72512 >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) We need to allocate to the last host again, so > checking if there is enough reserved capacity >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) We need to allocate to the last host again, so > checking if there is enough reserved capacity >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Reserved CPU: 0 , Requested CPU: 2000 >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Reserved CPU: 0 , Requested CPU: 2000 >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Reserved RAM: 0 , Requested RAM: 2147483648 >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) STATS: Failed to alloc resource from host: 89 > reservedCpu: 0, requested cpu: 2000, reservedMem: 0, requested mem: > 2147483648 >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Host does not have enough reserved CPU available,= > cannot allocate to this host. >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) The last host of this VM does not have enough > capacity >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Cannot choose the last host to deploy this VM=20 >=20 > 2014-11-27 10:11:33,096 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Searching resources only under specified Cluster:= > 10 >=20 > 2014-11-27 10:11:33,100 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Checking resources in Cluster: 10 under Pod: 1 >=20 > 2014-11-27 10:11:33,100 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Calling HostAllocators to find suitable hosts >=20 > 2014-11-27 10:11:33,100 DEBUG [allocator.impl.FirstFitAllocator] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Looking for hosts in dc:= 1 > pod:1 cluster:10 >=20 > 2014-11-27 10:11:33,101 DEBUG [allocator.impl.FirstFitAllocator] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) FirstFitAllocator has 1 > hosts to check for allocation: [Host[-89-Routing]] >=20 > 2014-11-27 10:11:33,102 DEBUG [allocator.impl.FirstFitAllocator] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Found 1 hosts for > allocation after prioritization: [Host[-89-Routing]] >=20 > 2014-11-27 10:11:33,102 DEBUG [allocator.impl.FirstFitAllocator] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Looking for speed=3D2000= Mhz, > Ram=3D2048 >=20 > 2014-11-27 10:11:33,104 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Checking if host: 89 has= > enough capacity for requested CPU: 2000 and requested RAM: 2147483648 , > cpuOverprovisioningFactor: 2.0 >=20 > 2014-11-27 10:11:33,105 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Hosts's actual total CPU= : > 36256 and CPU after applying overprovisioning: 72512 >=20 > 2014-11-27 10:11:33,105 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Free CPU: 54512 , > Requested CPU: 2000 >=20 > 2014-11-27 10:11:33,105 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Free RAM: 5816790400 , > Requested RAM: 2147483648 >=20 > 2014-11-27 10:11:33,105 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Host has enough CPU and > RAM available >=20 > 2014-11-27 10:11:33,105 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) STATS: Can alloc CPU fro= m > host: 89, used: 18000, reserved: 0, actual total: 36256, total with > overprovisioning: 72512; requested cpu:2000,alloc_from_last_host?:false > ,considerReservedCapacity?: true >=20 > 2014-11-27 10:11:33,105 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) STATS: Can alloc MEM fro= m > host: 89, used: 18656264192, reserved: 0, total: 24473054592; requested me= m: > 2147483648,alloc_from_last_host?:false ,considerReservedCapacity?: true >=20 > 2014-11-27 10:11:33,105 DEBUG [allocator.impl.FirstFitAllocator] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Found a suitable host, > adding to list: 89 >=20 > 2014-11-27 10:11:33,105 DEBUG [allocator.impl.FirstFitAllocator] > (HA-Worker-1:work-14354 FirstFitRoutingAllocator) Host Allocator returning= 1 > suitable hosts >=20 > 2014-11-27 10:11:33,106 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Checking suitable pools for volume (Id, Type): > (7333,ROOT) >=20 > 2014-11-27 10:11:33,106 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Volume is in READY state and has pool already > allocated, checking if pool can be reused, poolId: 514 >=20 > 2014-11-27 10:11:33,107 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Planner need not allocate a pool for this volume > since its READY >=20 > 2014-11-27 10:11:33,107 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Trying to find a potenial host and associated > storage pools from the suitable host/pool lists for this VM >=20 > 2014-11-27 10:11:33,107 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Checking if host: 89 can access any suitable > storage pool for volume: ROOT >=20 > 2014-11-27 10:11:33,108 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Host: 89 can access pool: 514 >=20 > 2014-11-27 10:11:33,108 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Found a potential host id: 89 name: xenserver203 > and associated storage pools for this VM >=20 > 2014-11-27 10:11:33,109 DEBUG [cloud.deploy.FirstFitPlanner] > (HA-Worker-1:work-14354) Returning Deployment Destination: > Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(I= d) > )] : Dest[Zone(1)-Pod(1)-Cluster(10)-Host(89)-Storage()] >=20 > 2014-11-27 10:11:33,109 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (HA-Worker-1:work-14354) Deployment found - > P0=3DVM[User|9ef850c6-d899-4080-b7fc-af9b39125408], > P0=3DDest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->P= ool( > Id))] : Dest[Zone(1)-Pod(1)-Cluster(10)-Host(89)-Storage()] >=20 > 2014-11-27 10:11:33,153 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) VM state transitted from :Starting to Starting wi= th > event: OperationRetryvm's original host id: 89 new host id: 89 host id > before state transition: null >=20 > 2014-11-27 10:11:33,154 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) VM starting again on the last host it was stopped= > on >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Hosts's actual total CPU: 36256 and CPU after > applying overprovisioning: 72512 >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) We are allocating VM, increasing the used capacit= y > of this host:89 >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) We are allocating VM, increasing the used capacit= y > of this host:89 >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Current Used CPU: 18000 , Free CPU:54512 ,Request= ed > CPU: 2000 >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Current Used RAM: 18656264192 , Free RAM:58167904= 00 > ,Requested RAM: 2147483648 >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) We are allocating VM to the last host again, so > adjusting the reserved capacity if it is not less than required >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Reserved CPU: 0 , Requested CPU: 2000 >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) Reserved RAM: 0 , Requested RAM: 2147483648 >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) CPU STATS after allocation: for host: 89, old use= d: > 18000, old reserved: 0, actual total: 36256, total with overprovisioning: > 72512; new used:20000, reserved:0; requested cpu:2000,alloc_from_last:true= >=20 > 2014-11-27 10:11:33,157 DEBUG [cloud.capacity.CapacityManagerImpl] > (HA-Worker-1:work-14354) RAM STATS after allocation: for host: 89, old use= d: > 18656264192, old reserved: 0, total: 24473054592; new used: 20803747840, > reserved: 0; requested mem: 2147483648,alloc_from_last:true >=20 > 2014-11-27 10:11:33,188 DEBUG [cloud.vm.VirtualMachineManagerImpl] > (HA-Worker-1:work-14354) VM is being created in podId: 1 >=20 > 2014-11-27 10:11:33,191 DEBUG [cloud.network.NetworkManagerImpl] > (HA-Worker-1:work-14354) Lock is acquired for network id 204 as a part of > network implement >=20 > 2014-11-27 10:11:33,191 DEBUG [cloud.network.NetworkManagerImpl] > (HA-Worker-1:work-14354) Network id=3D204 is already implemented >=20 > 2014-11-27 10:11:33,191 DEBUG [cloud.network.NetworkManagerImpl] > (HA-Worker-1:work-14354) Lock is released for network id 204 as a part of > network implement >=20 > 2014-11-27 10:11:33,226 DEBUG [cloud.network.NetworkManagerImpl] > (HA-Worker-1:work-14354) Changing active number of nics for network id=3D2= 04 > on 1 >=20 > 2014-11-27 10:11:33,249 DEBUG [cloud.network.NetworkManagerImpl] > (HA-Worker-1:work-14354) Asking JuniperSRX to prepare for > Nic[26254-7162-b817996e-d3f0-4e62-b6e5-3bef8ca02848-192.168.3.246] >=20 > 2014-11-27 10:11:33,251 DEBUG [cloud.network.NetworkManagerImpl] > (HA-Worker-1:work-14354) Asking Netscaler to prepare for > Nic[26254-7162-b817996e-d3f0-4e62-b6e5-3bef8ca02848-192.168.3.246] >=20 > 2014-11-27 10:11:33,252 DEBUG [cloud.network.NetworkManagerImpl] > (HA-Worker-1:work-14354) Asking F5BigIP to prepare for > Nic[26254-7162-b817996e-d3f0-4e62-b6e5-3bef8ca02848-192.168.3.246] >=20 > 2014-11-27 10:11:33,254 DEBUG [cloud.network.NetworkManagerImpl] > (HA-Worker-1:work-14354) Asking VirtualRouter to prepare for > Nic[26254-7162-b817996e-d3f0-4e62-b6e5-3bef8ca02848-192.168.3.246] >=20 > 2014-11-27 10:11:33,462 DEBUG > [network.router.VirtualNetworkApplianceManagerImpl] (HA-Worker-1:work-1435= 4) > Lock is acquired for network id 204 as a part of router startup in > Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(I= d) > )] : Dest[Zone(1)-Pod(1)-Cluster(10)-Host(89)-Storage()] >=20 > 2014-11-27 10:11:33,465 DEBUG > [network.router.VirtualNetworkApplianceManagerImpl] (HA-Worker-1:work-1435= 4) > Lock is released for network id 204 as a part of router startup in > Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(I= d) > )] : Dest[Zone(1)-Pod(1)-Cluster(10)-Host(89)-Storage()] >=20 > 2014-11-27 10:11:33,472 DEBUG > [network.router.VirtualNetworkApplianceManagerImpl] (HA-Worker-1:work-1435= 4) > Applying dhcp entry in network Ntwk[204|Guest|6] >=20 > 2014-11-27 10:11:33,486 DEBUG [agent.manager.ClusteredAgentAttache] > (HA-Worker-1:work-14354) Seq 89-2067791929: Forwarding Seq 89-2067791929: = { > Cmd , MgmtId: 110480925280, via: 89, Ver: v1, Flags: 100111, > [{"routing.DhcpEntryCommand":{"vmMac":"06:57:b0:00:00:79","vmIpAddress":"1= 92 > .168.3.246","vmName":"9ef850c6-d899-4080-b7fc-af9b39125408","defaultRouter= ": > "192.168.3.51","defaultDns":"192.168.3.172","accessDetails":{"router.guest= .i > p":"192.168.3.172","zone.network.type":"Basic","router.name":"r-7170-VM","= ro > uter.ip":"169.254.1.106"},"wait":0}}] } to 165036460088 >=20 > 2014-11-27 10:11:43,397 DEBUG > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone > host is ready, but secondary storage vm template: 1 is not ready on > secondary storage: 27 >=20 > 2014-11-27 10:11:43,397 DEBUG > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1= > is not ready to launch secondary storage VM yet >=20 > 2014-11-27 10:11:47,741 DEBUG > [network.router.VirtualNetworkApplianceManagerImpl] > (RouterStatusMonitor-1:null) Found 0 routers.=20 >=20 > 2014-11-27 10:11:53,388 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-332:null) Ping from 76 >=20 > 2014-11-27 10:11:53,396 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-31:null) Ping from 43 >=20 > 2014-11-27 10:11:54,182 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-303:null) Seq 76-2043346946: Executing request >=20 > 2014-11-27 10:11:54,340 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-404:null) Seq 43-396689410: Executing request >=20 > 2014-11-27 10:11:54,654 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-303:null) Seq 76-2043346946: Response Received:=20 >=20 > 2014-11-27 10:11:54,655 DEBUG [agent.transport.Request] > (DirectAgent-303:null) Seq 76-2043346946: Processing: { Ans: , MgmtId: > 110480925280, via: 76, Ver: v1, Flags: 10, > [{"ClusterSyncAnswer":{"_clusterId":4,"_newStates":{},"_isExecuted":false,= "r > esult":true,"wait":0}}] } >=20 > 2014-11-27 10:11:54,919 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-404:null) Seq 43-396689410: Response Received:=20 >=20 > 2014-11-27 10:11:54,919 DEBUG [agent.transport.Request] > (DirectAgent-404:null) Seq 43-396689410: Processing: { Ans: , MgmtId: > 110480925280, via: 43, Ver: v1, Flags: 10, > [{"ClusterSyncAnswer":{"_clusterId":1,"_newStates":{},"_isExecuted":false,= "r > esult":true,"wait":0}}] } >=20 > 2014-11-27 10:12:13,397 DEBUG > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1= > is ready to launch secondary storage VM >=20 > 2014-11-27 10:12:17,741 DEBUG > [network.router.VirtualNetworkApplianceManagerImpl] > (RouterStatusMonitor-1:null) Found 0 routers.=20 >=20 > 2014-11-27 10:12:43,396 DEBUG > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone > host is ready, but secondary storage vm template: 1 is not ready on > secondary storage: 95 >=20 > 2014-11-27 10:12:43,397 DEBUG > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1= > is not ready to launch secondary storage VM yet >=20 > 2014-11-27 10:12:43,397 DEBUG > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1= > is not ready to launch secondary storage VM yet >=20 > 2014-11-27 10:12:47,741 DEBUG > [network.router.VirtualNetworkApplianceManagerImpl] > (RouterStatusMonitor-1:null) Found 0 routers.=20 >=20 > 2014-11-27 10:12:53,388 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-201:null) Ping from 43 >=20 > 2014-11-27 10:12:53,388 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-250:null) Ping from 76 >=20 > 2014-11-27 10:12:53,388 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-250:null) Ping from 76 >=20 > 2014-11-27 10:12:54,182 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-36:null) Seq 76-2043346946: Executing request >=20 > 2014-11-27 10:12:54,339 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-250:null) Seq 43-396689410: Executing request >=20 > 2014-11-27 10:12:54,654 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-36:null) Seq 76-2043346946: Response Received:=20 >=20 > 2014-11-27 10:12:54,655 DEBUG [agent.transport.Request] > (DirectAgent-36:null) Seq 76-2043346946: Processing: { Ans: , MgmtId: > 110480925280, via: 76, Ver: v1, Flags: 10, > [{"ClusterSyncAnswer":{"_clusterId":4,"_newStates":{},"_isExecuted":false,= "r > esult":true,"wait":0}}] } >=20 > 2014-11-27 10:12:54,906 DEBUG [agent.manager.DirectAgentAttache] > (DirectAgent-250:null) Seq 43-396689410: Response Received:=20 >=20 > 2014-11-27 10:12:54,906 DEBUG [agent.transport.Request] > (DirectAgent-250:null) Seq 43-396689410: Processing: { Ans: , MgmtId: > 110480925280, via: 43, Ver: v1, Flags: 10, > [{"ClusterSyncAnswer":{"_clusterId":1,"_newStates":{},"_isExecuted":false,= "r > esult":true,"wait":0}}] } >=20 > 2014-11-27 10:13:13,396 DEBUG > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone > host is ready, but secondary storage vm template: 1 is not ready on > secondary storage: 8 >=20 > 2014-11-27 10:13:13,396 DEBUG > [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1= > is not ready to launch secondary storage VM yet >=20 > aryStorageManagerImpl] (secstorage-1:null) Zone 1 is not ready to launch > secondary storage VM yet >=20 > 2014-11-27 10:13:17,741 DEBUG > [network.router.VirtualNetworkApplianceManagerImpl] > (RouterStatusMonitor-1:null) Found 0 routers. >=20 >=20 >=20 >=20 >=20 >=20