Mailing-List: contact users-cn-help@cloudstack.apache.org; run by ezmlm
Precedence: bulk
Reply-To: users-cn@cloudstack.apache.org
Received-SPF: pass (athena.apache.org: domain of clayone23@gmail.com
 designates 209.85.212.41 as permitted sender)
MIME-Version: 1.0
Date: Thu, 16 May 2013 15:49:58 +0800
Message-ID: 
 <CAJ0L4a=tHV+ONcmoC3jEdSvEho2moCL3iU1aG02Wexypj1js5w@mail.gmail.com>
Subject: =?GB2312?B?zbvIu7K7xNy0tL2o0Om7+qOs0rKyu8Tc1tjG9NDpu/rByw==?=
From: tao huang <clayone23@gmail.com>
To: users-cn@cloudstack.apache.org
Content-Type: multipart/alternative; boundary=047d7bea2ec6efd6ac04dcd11c2d

--047d7bea2ec6efd6ac04dcd11c2d
Content-Type: text/plain; charset=GB2312
Content-Transfer-Encoding: quoted-printable

=B8=F7=CE=BB=BA=C3=A3=ACcloudstack 3.0 +xenserver6.0=BB=B7=BE=B3=A3=AC=B8=
=DF=BC=B6=CD=F8=C2=E7
=CD=BB=C8=BB=CE=DE=B7=A8=B4=B4=BD=A8=D0=E9=BB=FA=A3=AC=B8=D0=BE=F5=CA=C7=D6=
=F7=B4=E6=B4=A2=B3=F6=C1=CB=CE=CA=CC=E2=A3=AC=B5=AB=CA=C7=BF=B4=D7=B4=CC=AC=
=CA=C7=D5=FD=B3=A3=B5=C4=A3=AC=C8=E7=BA=CE=C5=C5=B2=E9=C4=D8=A3=AC=D0=BB=D0=
=BB=D6=B8=B5=BC


2013-05-16 15:29:40,062 DEBUG [agent.manager.DirectAgentAttache]
(DirectAgent-369:null) Seq 3-939886253: Response Received:
2013-05-16 15:29:40,062 DEBUG [agent.transport.Request]
(DirectAgent-369:null) Seq 3-939886253: Processing:  { Ans: , MgmtId:
33932935549676, via: 3, Ver: v1, Flags: 110,
[{"StopAnswer":{"result":false,"details":"Exception:
com.cloud.utils.exception.CloudRuntimeException\nMessage: *Unable to reset
master of slave 192.168.1.38 to 192.168.1.36 due to
org.apache.xmlrpc.XmlRpcException: Failed to create input stream: Read
timed out\nStack: com.cloud.utils.exception.CloudRuntimeException: Unable
to reset master of slave 192.168.1.38 to 192.168.1.36 due to
org.apache.xmlrpc.*XmlRpcException: Failed to create input stream: Read
timed out\n\tat
com.cloud.hypervisor.xen.resource.XenServerConnectionPool.PoolEmergencyRese=
tMaster(XenServerConnectionPool.java:439)\n\tat
com.cloud.hypervisor.xen.resource.XenServerConnectionPool.connect(XenServer=
ConnectionPool.java:657)\n\tat
com.cloud.hypervisor.xen.resource.CitrixResourceBase.getConnection(CitrixRe=
sourceBase.java:5090)\n\tat
com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResource=
Base.java:3284)\n\tat
com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixR=
esourceBase.java:416)\n\tat
com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenSer=
ver56Resource.java:69)\n\tat
com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java=
:187)\n\tat
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)\n\t=
at
java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)\n\tat
java.util.concurrent.FutureTask.run(FutureTask.java:166)\n\tat
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access=
$101(ScheduledThreadPoolExecutor.java:165)\n\tat
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Sc=
heduledThreadPoolExecutor.java:266)\n\tat
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1=
110)\n\tat
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:=
603)\n\tat
java.lang.Thread.run(Thread.java:636)\n","wait":0}}] }
2013-05-16 15:29:40,062 DEBUG [agent.manager.AgentAttache]
(DirectAgent-369:null) Seq 3-939886253: No more commands found
2013-05-16 15:29:40,062 DEBUG [agent.transport.Request]
(Job-Executor-6:job-18181) Seq 3-939886253: Received:  { Ans: , MgmtId:
33932935549676, via: 3, Ver: v1, Flags: 110, { StopAnswer } }
2013-05-16 15:29:40,062 DEBUG [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-6:job-18181) Unable to stop VM due to Exception:
com.cloud.utils.exception.CloudRuntimeException
Message: Unable to reset master of slave 192.168.1.38 to 192.168.1.36 due
to org.apache.xmlrpc.XmlRpcException: Failed to create input stream: Read
timed out
Stack: com.cloud.utils.exception.CloudRuntimeException: Unable to reset
master of slave 192.168.1.38 to 192.168.1.36 due to
org.apache.xmlrpc.XmlRpcException: Failed to create input stream: Read
timed out
        at
com.cloud.hypervisor.xen.resource.XenServerConnectionPool.PoolEmergencyRese=
tMaster(XenServerConnectionPool.java:439)
        at
com.cloud.hypervisor.xen.resource.XenServerConnectionPool.connect(XenServer=
ConnectionPool.java:657)
        at
com.cloud.hypervisor.xen.resource.CitrixResourceBase.getConnection(CitrixRe=
sourceBase.java:5090)
        at
com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResource=
Base.java:3284)
        at
com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixR=
esourceBase.java:416)
        at
com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenSer=
ver56Resource.java:69)
        at
com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java=
:187)
        at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
        at
java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
        at java.util.concurrent.FutureTask.run(FutureTask.java:166)
        at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access=
$101(ScheduledThreadPoolExecutor.java:165)
        at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Sc=
heduledThreadPoolExecutor.java:266)
        at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1=
110)
        at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:=
603)
        at java.lang.Thread.run(Thread.java:636)

2013-05-16 15:29:40,062 WARN  [cloud.vm.VirtualMachineManagerImpl]
(Job-Executor-6:job-18181) Failed to stop vm VM[DomainRouter|r-2237-VM] in
Starting state as a part of cleanup process
2013-05-16 15:29:40,066 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) DeploymentPlanner allocation algorithm: random
2013-05-16 15:29:40,066 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) Trying to allocate a host and storage pools from
dc:1, pod:null,cluster:null, requested cpu: 500, requested ram: 134217728
2013-05-16 15:29:40,066 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) Is ROOT volume READY (pool already allocated)?:
No
2013-05-16 15:29:40,066 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) Searching all possible resources under this
Zone: 1
2013-05-16 15:29:40,068 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) Listing clusters in order of aggregate capacity,
that have (atleast one host with) enough CPU and RAM capacity under this
Zone: 1
2013-05-16 15:29:40,069 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) CPUOverprovisioningFactor considered: 3.0
2013-05-16 15:29:40,077 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) Checking resources in Cluster: 1 under Pod: 1
2013-05-16 15:29:40,077 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) Calling HostAllocators to find suitable hosts
2013-05-16 15:29:40,077 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Looking for hosts in
dc: 1  pod:1  cluster:1
2013-05-16 15:29:40,080 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) FirstFitAllocator has 7
hosts to check for allocation: [Host[-4-Routing], Host[-2-Routing],
Host[-1-Routing], Host[-3-Routing], Host[-8-Routing], Host[-7-Routing],
Host[-15-Routing]]
2013-05-16 15:29:40,088 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Found 7 hosts for
allocation after prioritization: [Host[-4-Routing], Host[-2-Routing],
Host[-1-Routing], Host[-3-Routing], Host[-8-Routing], Host[-7-Routing],
Host[-15-Routing]]
2013-05-16 15:29:40,088 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Looking for
speed=3D500Mhz, Ram=3D128
2013-05-16 15:29:40,091 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Checking if host: 4 has
enough capacity for requested CPU: 500 and requested RAM: 134217728 ,
cpuOverprovisioningFactor: 3.0
2013-05-16 15:29:40,093 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Hosts's actual total
CPU: 63984 and CPU after applying overprovisioning: 191952
2013-05-16 15:29:40,093 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Free CPU: 161952 ,
Requested CPU: 500
2013-05-16 15:29:40,093 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Free RAM: 71156002944 ,
Requested RAM: 134217728
2013-05-16 15:29:40,093 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Host has enough CPU and
RAM available
2013-05-16 15:29:40,093 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) STATS: Can alloc CPU
from host: 4, used: 29500, reserved: 500, actual total: 63984, total with
overprovisioning: 191952; requested cpu:500,alloc_from_last_host?:false
,considerReservedCapacity?: true
2013-05-16 15:29:40,093 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) STATS: Can alloc MEM
from host: 4, used: 28856811520, reserved: 536870912, total: 100549685376;
requested mem: 134217728,alloc_from_last_host?:false
,considerReservedCapacity?: true
2013-05-16 15:29:40,093 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Found a suitable host,
adding to list: 4
2013-05-16 15:29:40,095 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Checking if host: 2 has
enough capacity for requested CPU: 500 and requested RAM: 134217728 ,
cpuOverprovisioningFactor: 3.0
2013-05-16 15:29:40,097 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Hosts's actual total
CPU: 63984 and CPU after applying overprovisioning: 191952
2013-05-16 15:29:40,097 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Free CPU: 151452 ,
Requested CPU: 500
2013-05-16 15:29:40,097 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Free RAM: 58539536512 ,
Requested RAM: 134217728
2013-05-16 15:29:40,097 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Host has enough CPU and
RAM available
2013-05-16 15:29:40,097 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) STATS: Can alloc CPU
from host: 2, used: 40500, reserved: 0, actual total: 63984, total with
overprovisioning: 191952; requested cpu:500,alloc_from_last_host?:false
,considerReservedCapacity?: true
2013-05-16 15:29:40,097 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) STATS: Can alloc MEM
from host: 2, used: 42010148864, reserved: 0, total: 100549685376;
requested mem: 134217728,alloc_from_last_host?:false
,considerReservedCapacity?: true
2013-05-16 15:29:40,097 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Found a suitable host,
adding to list: 2
2013-05-16 15:29:40,114 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Found a suitable host,
adding to list: 15
*2013-05-16 15:29:40,114 DEBUG [allocator.impl.FirstFitAllocator]
(Job-Executor-6:job-18181 FirstFitRoutingAllocator) Host Allocator
returning 6 suitable hosts*
*2013-05-16 15:29:40,116 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) Checking suitable pools for volume (Id, Type):
(2438,ROOT)*
*2013-05-16 15:29:40,116 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) We need to allocate new storagepool for this
volume*
*2013-05-16 15:29:40,116 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) Calling StoragePoolAllocators to find suitable
pools*
*2013-05-16 15:29:40,117 DEBUG
[storage.allocator.FirstFitStoragePoolAllocator] (Job-Executor-6:job-18181)
Looking for pools in dc: 1  pod:1  cluster:1*
*2013-05-16 15:29:40,118 DEBUG
[storage.allocator.FirstFitStoragePoolAllocator] (Job-Executor-6:job-18181)
FirstFitStoragePoolAllocator has 1 pools to check for allocation*
*2013-05-16 15:29:40,118 DEBUG
[storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-6:job-18181)
Checking if storage pool is suitable, name: ps ,poolId: 200*
*2013-05-16 15:29:40,118 DEBUG
[storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-6:job-18181)
StoragePool is in avoid set, skipping this pool*
*2013-05-16 15:29:40,118 DEBUG
[storage.allocator.FirstFitStoragePoolAllocator] (Job-Executor-6:job-18181)
FirstFitStoragePoolAllocator returning 0 suitable storage pools*
*2013-05-16 15:29:40,118 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) No suitable pools found for volume:
Vol[2438|vm=3D2237|ROOT] under cluster: 1*
*2013-05-16 15:29:40,118 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) No suitable pools found*
*2013-05-16 15:29:40,118 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) No suitable storagePools found under this
Cluster: 1*
*2013-05-16 15:29:40,118 DEBUG [cloud.deploy.FirstFitPlanner]
(Job-Executor-6:job-18181) Could not find suitable Deployment Destination
for this VM under any clusters, returning.*
*2013-05-16 15:29:40,135 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181) VM state transitted from :Starting to Stopped
with event: OperationFailedvm's original host id: null new host id: null
host id before state transition: 3*
*2013-05-16 15:29:40,139 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181) Hosts's actual total CPU: 63984 and CPU after
applying overprovisioning: 191952*
*2013-05-16 15:29:40,139 DEBUG [cloud.capacity.CapacityManagerImpl]
(Job-Executor-6:job-18181) release cpu from host: 3, old used:
29000,reserved: 0, actual total: 63984, total with overprovisioning:
191952; new used: 28500,reserved:0; movedfromreserved:
false,moveToReserveredfalse*

--047d7bea2ec6efd6ac04dcd11c2d--