> On 十二月 16, 2014, 10:34 a.m., Erik Weber wrote:
> > I'm getting this error when trying to run with the latest 4.4 branch:
> > 
> > 2014-12-16 11:01:41,025 DEBUG [c.c.v.VmWorkJobDispatcher] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70) Run VM work job: 
> > com.cloud.vm.VmWorkStart for VM 1, job origin: 20
> > 2014-12-16 11:01:41,027 DEBUG [c.c.v.VmWorkJobHandlerProxy] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Execute VM 
> > work job: 
> > com.cloud.vm.VmWorkStart{"dcId":0,"userId":1,"accountId":1,"vmId":1,"handlerName":"VirtualMachineManagerImpl"}
> > 2014-12-16 11:01:41,035 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) 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-12-16 11:01:41,035 DEBUG [c.c.v.VirtualMachineManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Successfully 
> > transitioned to start state for VM[ConsoleProxy|v-1-VM] reservation id = 
> > efd0d2cc-cc6f-44b3-b373-e8f0ecf3e33e
> > 2014-12-16 11:01:41,039 DEBUG [c.c.v.VirtualMachineManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Trying to 
> > deploy VM, vm has dcId: 1 and podId: 1
> > 2014-12-16 11:01:41,039 DEBUG [c.c.v.VirtualMachineManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Deploy 
> > avoids pods: null, clusters: null, hosts: null
> > 2014-12-16 11:01:41,043 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Deploy 
> > avoids pods: null, clusters: null, hosts: null
> > 2014-12-16 11:01:41,044 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) 
> > DeploymentPlanner allocation algorithm: 
> > com.cloud.deploy.FirstFitPlanner@663f6fad
> > 2014-12-16 11:01:41,044 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Trying to 
> > allocate a host and storage pools from dc:1, pod:1,cluster:null, requested 
> > cpu: 500, requested ram: 1073741824
> > 2014-12-16 11:01:41,044 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Is ROOT 
> > volume READY (pool already allocated)?: No
> > 2014-12-16 11:01:41,044 DEBUG [c.c.d.FirstFitPlanner] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Searching 
> > resources only under specified Pod: 1
> > 2014-12-16 11:01:41,045 DEBUG [c.c.d.FirstFitPlanner] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Listing 
> > clusters in order of aggregate capacity, that have (atleast one host with) 
> > enough CPU and RAM capacity under this Pod: 1
> > 2014-12-16 11:01:41,054 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Checking 
> > resources in Cluster: 1 under Pod: 1
> > 2014-12-16 11:01:41,054 DEBUG [c.c.a.m.a.i.FirstFitAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Looking for hosts in dc: 1  pod:1  cluster:1
> > 2014-12-16 11:01:41,057 DEBUG [c.c.a.m.a.i.FirstFitAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) FirstFitAllocator has 1 hosts to check for 
> > allocation: [Host[-1-Routing]]
> > 2014-12-16 11:01:41,061 DEBUG [c.c.a.m.a.i.FirstFitAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Found 1 hosts for allocation after 
> > prioritization: [Host[-1-Routing]]
> > 2014-12-16 11:01:41,062 DEBUG [c.c.a.m.a.i.FirstFitAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=1024
> > 2014-12-16 11:01:41,068 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Host: 1 has cpu capability (cpu:2, speed:2399) to 
> > support requested CPU: 1 and requested speed: 500
> > 2014-12-16 11:01:41,068 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Checking if host: 1 has enough capacity for 
> > requested CPU: 500 and requested RAM: 1073741824 , 
> > cpuOverprovisioningFactor: 1.0
> > 2014-12-16 11:01:41,071 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Hosts's actual total CPU: 4798 and CPU after 
> > applying overprovisioning: 4798
> > 2014-12-16 11:01:41,071 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Free CPU: 4298 , Requested CPU: 500
> > 2014-12-16 11:01:41,071 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Free RAM: 6890268672 , Requested RAM: 1073741824
> > 2014-12-16 11:01:41,071 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Host has enough CPU and RAM available
> > 2014-12-16 11:01:41,071 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1, used: 500, 
> > reserved: 0, actual total: 4798, total with overprovisioning: 4798; 
> > requested cpu:500,alloc_from_last_host?:false ,considerReservedCapacity?: 
> > true
> > 2014-12-16 11:01:41,071 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1, used: 
> > 536870912, reserved: 0, total: 7427139584; requested mem: 
> > 1073741824,alloc_from_last_host?:false ,considerReservedCapacity?: true
> > 2014-12-16 11:01:41,071 DEBUG [c.c.a.m.a.i.FirstFitAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Found a suitable host, adding to list: 1
> > 2014-12-16 11:01:41,071 DEBUG [c.c.a.m.a.i.FirstFitAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137 
> > FirstFitRoutingAllocator) Host Allocator returning 1 suitable hosts
> > 2014-12-16 11:01:41,073 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Checking 
> > suitable pools for volume (Id, Type): (1,ROOT)
> > 2014-12-16 11:01:41,073 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) We need to 
> > allocate new storagepool for this volume
> > 2014-12-16 11:01:41,074 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Calling 
> > StoragePoolAllocators to find suitable pools
> > 2014-12-16 11:01:41,075 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) 
> > LocalStoragePoolAllocator trying to find storage pool to fit the vm
> > 2014-12-16 11:01:41,075 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) 
> > ClusterScopeStoragePoolAllocator looking for storage pool
> > 2014-12-16 11:01:41,075 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Looking for 
> > pools in dc: 1  pod:1  cluster:1
> > 2014-12-16 11:01:41,077 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Found pools 
> > matching tags: [Pool[2|NetworkFilesystem]]
> > 2014-12-16 11:01:41,078 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Removing 
> > pool Pool[2|NetworkFilesystem] from avoid set, must have been inserted when 
> > searching for another disk's tag
> > 2014-12-16 11:01:41,080 DEBUG [o.a.c.s.a.AbstractStoragePoolAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Checking if 
> > storage pool is suitable, name: null ,poolId: 2
> > 2014-12-16 11:01:41,083 DEBUG [c.c.s.StorageManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Checking 
> > pool 2 for storage, totalSize: 32615956480, usedBytes: 7256670208, usedPct: 
> > 0.22248834592509242, disable threshold: 0.85
> > 2014-12-16 11:01:41,089 DEBUG [c.c.s.StorageManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Found 
> > storage pool nfs of type NetworkFilesystem with overprovisioning factor 2
> > 2014-12-16 11:01:41,089 DEBUG [c.c.s.StorageManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Total over 
> > provisioned capacity calculated is 2 * 32615956480
> > 2014-12-16 11:01:41,089 DEBUG [c.c.s.StorageManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Total 
> > capacity of the pool nfs id: 2 is 65231912960
> > 2014-12-16 11:01:41,090 DEBUG [c.c.s.StorageManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Checking 
> > pool: 2 for volume allocation [Vol[1|vm=1|ROOT]], maxSize : 65231912960, 
> > totalAllocatedSize : 0, askingSize : 0, allocated disable threshold: 0.85
> > 2014-12-16 11:01:41,090 DEBUG [o.a.c.s.a.ClusterScopeStoragePoolAllocator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) 
> > ClusterScopeStoragePoolAllocator returning 1 suitable storage pools
> > 2014-12-16 11:01:41,090 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Trying to 
> > find a potenial host and associated storage pools from the suitable 
> > host/pool lists for this VM
> > 2014-12-16 11:01:41,090 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Checking if 
> > host: 1 can access any suitable storage pool for volume: ROOT
> > 2014-12-16 11:01:41,091 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Host: 1 can 
> > access pool: 2
> > 2014-12-16 11:01:41,092 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Found a 
> > potential host id: 1 name: acs-xenresize-xen01 and associated storage pools 
> > for this VM
> > 2014-12-16 11:01:41,093 DEBUG [c.c.d.DeploymentPlanningManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Returning 
> > Deployment Destination: 
> > Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
> >  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(1|ROOT-->Pool(2))]
> > 2014-12-16 11:01:41,093 DEBUG [c.c.v.VirtualMachineManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Deployment 
> > found  - P0=VM[ConsoleProxy|v-1-VM], 
> > P0=Dest[Zone(Id)-Pod(Id)-Cluster(Id)-Host(Id)-Storage(Volume(Id|Type-->Pool(Id))]
> >  : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(1|ROOT-->Pool(2))]
> > 2014-12-16 11:01:41,102 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) VM state 
> > transitted from :Starting to Starting with event: OperationRetryvm's 
> > original host id: null new host id: 1 host id before state transition: null
> > 2014-12-16 11:01:41,109 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Hosts's 
> > actual total CPU: 4798 and CPU after applying overprovisioning: 4798
> > 2014-12-16 11:01:41,109 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) We are 
> > allocating VM, increasing the used capacity of this host:1
> > 2014-12-16 11:01:41,109 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Current Used 
> > CPU: 500 , Free CPU:4298 ,Requested CPU: 500
> > 2014-12-16 11:01:41,109 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Current Used 
> > RAM: 536870912 , Free RAM:6890268672 ,Requested RAM: 1073741824
> > 2014-12-16 11:01:41,109 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) CPU STATS 
> > after allocation: for host: 1, old used: 500, old reserved: 0, actual 
> > total: 4798, total with overprovisioning: 4798; new used:1000, reserved:0; 
> > requested cpu:500,alloc_from_last:false
> > 2014-12-16 11:01:41,109 DEBUG [c.c.c.CapacityManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) RAM STATS 
> > after allocation: for host: 1, old used: 536870912, old reserved: 0, total: 
> > 7427139584; new used: 1610612736, reserved: 0; requested mem: 
> > 1073741824,alloc_from_last:false
> > 2014-12-16 11:01:41,112 DEBUG [c.c.v.VirtualMachineManagerImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) VM is being 
> > created in podId: 1
> > 2014-12-16 11:01:41,121 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Network 
> > id=204 is already implemented
> > 2014-12-16 11:01:41,131 DEBUG [c.c.n.NetworkModelImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Service 
> > SecurityGroup is not supported in the network id=204
> > 2014-12-16 11:01:41,142 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Asking 
> > VirtualRouter to prepare for 
> > Nic[1-1-efd0d2cc-cc6f-44b3-b373-e8f0ecf3e33e-10.18.252.120]
> > 2014-12-16 11:01:41,143 DEBUG [c.c.n.NetworkModelImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Service 
> > SecurityGroup is not supported in the network id=204
> > 2014-12-16 11:01:41,145 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Network 
> > id=202 is already implemented
> > 2014-12-16 11:01:41,168 DEBUG [o.a.c.e.o.NetworkOrchestrator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Network 
> > id=201 is already implemented
> > 2014-12-16 11:01:41,184 DEBUG [c.c.n.g.PodBasedNetworkGuru] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Allocated a 
> > nic NicProfile[3-1-efd0d2cc-cc6f-44b3-b373-e8f0ecf3e33e-10.18.252.108-null 
> > for VM[ConsoleProxy|v-1-VM]
> > 2014-12-16 11:01:41,189 DEBUG [o.a.c.e.o.VolumeOrchestrator] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Checking if 
> > we need to prepare 1 volumes for VM[ConsoleProxy|v-1-VM]
> > 2014-12-16 11:01:41,200 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) template 1 
> > is already in store:1, type:Image
> > 2014-12-16 11:01:41,204 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) template 1 
> > is already in store:2, type:Primary
> > 2014-12-16 11:01:41,217 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) copyAsync 
> > inspecting src type TEMPLATE copyAsync inspecting dest type VOLUME
> > 2014-12-16 11:01:41,228 DEBUG [c.c.a.t.Request] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Seq 
> > 1-1590896568368627899: Sending  { Cmd , MgmtId: 345051613899, via: 
> > 1(acs-xenresize-xen01), Ver: v1, Flags: 100011, 
> > [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5f6df867-86d8-449c-b1eb-ae8760c85a56","origUrl":"http://cloudstack.apt-get.eu/systemvm/4.4/systemvm64template-4.4.0-6-xen.vhd.bz2","uuid":"f29bbc9a-8504-11e4-ad06-005056adcacb","id":1,"format":"VHD","accountId":1,"checksum":"7a8a6c4f8478147f1c6aa0d20f5e62b9","hvm":false,"displayText":"SystemVM
> >  Template 
> > (XenServer)","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"1a215646-6057-3049-93d4-49bd238043c0","id":2,"poolType":"NetworkFilesystem","host":"10.18.252.40","path":"/primary","port":2049,"url":"NetworkFilesystem://10.18.252.40/primary/?ROLE=Primary&STOREUUID=1a215646-6057-3049-93d4-49bd238043c0"}},"
 
name":"routing-1","hypervisorType":"XenServer"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f33e06ec-05e0-4263-85ff-4cbce73f03ab","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"1a215646-6057-3049-93d4-49bd238043c0","id":2,"poolType":"NetworkFilesystem","host":"10.18.252.40","path":"/primary","port":2049,"url":"NetworkFilesystem://10.18.252.40/primary/?ROLE=Primary&STOREUUID=1a215646-6057-3049-93d4-49bd238043c0"}},"name":"ROOT-1","size":0,"volumeId":1,"vmName":"v-1-VM","accountId":1,"format":"VHD","id":1,"deviceId":0,"hypervisorType":"XenServer"}},"executeInSequence":false,"options":{},"wait":0}}]
 }
> > 2014-12-16 11:01:41,230 DEBUG [c.c.a.t.Request] 
> > (Work-Job-Executor-49:ctx-49d1b9c2 job-20/job-70 ctx-59a62137) Seq 
> > 1-1590896568368627899: Executing:  { Cmd , MgmtId: 345051613899, via: 
> > 1(acs-xenresize-xen01), Ver: v1, Flags: 100011, 
> > [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"5f6df867-86d8-449c-b1eb-ae8760c85a56","origUrl":"http://cloudstack.apt-get.eu/systemvm/4.4/systemvm64template-4.4.0-6-xen.vhd.bz2","uuid":"f29bbc9a-8504-11e4-ad06-005056adcacb","id":1,"format":"VHD","accountId":1,"checksum":"7a8a6c4f8478147f1c6aa0d20f5e62b9","hvm":false,"displayText":"SystemVM
> >  Template 
> > (XenServer)","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"1a215646-6057-3049-93d4-49bd238043c0","id":2,"poolType":"NetworkFilesystem","host":"10.18.252.40","path":"/primary","port":2049,"url":"NetworkFilesystem://10.18.252.40/primary/?ROLE=Primary&STOREUUID=1a215646-6057-3049-93d4-49bd238043c0"}
 
},"name":"routing-1","hypervisorType":"XenServer"}},"destTO":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"f33e06ec-05e0-4263-85ff-4cbce73f03ab","volumeType":"ROOT","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"1a215646-6057-3049-93d4-49bd238043c0","id":2,"poolType":"NetworkFilesystem","host":"10.18.252.40","path":"/primary","port":2049,"url":"NetworkFilesystem://10.18.252.40/primary/?ROLE=Primary&STOREUUID=1a215646-6057-3049-93d4-49bd238043c0"}},"name":"ROOT-1","size":0,"volumeId":1,"vmName":"v-1-VM","accountId":1,"format":"VHD","id":1,"deviceId":0,"hypervisorType":"XenServer"}},"executeInSequence":false,"options":{},"wait":0}}]
 }
> > 2014-12-16 11:01:41,231 DEBUG [c.c.a.m.DirectAgentAttache] 
> > (DirectAgent-93:ctx-ee3cddb5) Seq 1-1590896568368627899: Executing request
> > 2014-12-16 11:01:42,075 DEBUG [o.a.c.f.j.d.VmWorkJobDaoImpl] 
> > (Vm-Operations-Cleanup-1:ctx-4d47805f) Expunge completed work job-68
> > 2014-12-16 11:01:42,079 DEBUG [o.a.c.f.j.d.VmWorkJobDaoImpl] 
> > (Vm-Operations-Cleanup-1:ctx-4d47805f) Expunge completed work job-69
> > 2014-12-16 11:01:42,223 WARN  [c.c.h.x.r.XenServerStorageProcessor] 
> > (DirectAgent-93:ctx-ee3cddb5) Unable to create volume; 
> > Pool=volumeTO[uuid=f33e06ec-05e0-4263-85ff-4cbce73f03ab|path=null|datastore=PrimaryDataStoreTO[uuid=1a215646-6057-3049-93d4-49bd238043c0|name=null|id=2|pooltype=NetworkFilesystem]];
> >  Disk: 
> > SR_BACKEND_FAILURE_79VDI Invalid size [opterr=shrinking not allowed]
> >     at com.xensource.xenapi.Types.checkResponse(Types.java:2006)
> >     at com.xensource.xenapi.Connection.dispatch(Connection.java:350)
> >     at 
> > com.cloud.hypervisor.xen.resource.XenServerConnectionPool$XenServerConnection.dispatch(XenServerConnectionPool.java:456)
> >     at com.xensource.xenapi.VDI.resize(VDI.java:1226)
> >     at 
> > com.cloud.hypervisor.xen.resource.XenServerStorageProcessor.cloneVolumeFromBaseTemplate(XenServerStorageProcessor.java:1056)
> >     at 
> > com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.execute(StorageSubsystemCommandHandlerBase.java:81)
> >     at 
> > com.cloud.storage.resource.StorageSubsystemCommandHandlerBase.handleStorageCommands(StorageSubsystemCommandHandlerBase.java:52)
> >     at 
> > com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:542)
> >     at 
> > com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:61)
> >     at 
> > com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:102)
> >     at 
> > com.cloud.agent.manager.DirectAgentAttache$Task.runInContext(DirectAgentAttache.java:307)
> >     at 
> > org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
> >     at 
> > org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
> >     at 
> > org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
> >     at 
> > org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
> >     at 
> > org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
> >     at 
> > java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> >     at java.util.concurrent.FutureTask.run(FutureTask.java:262)
> >     at 
> > java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:178)
> >     at 
> > java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:292)
> >     at 
> > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
> >     at 
> > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
> >     at java.lang.Thread.run(Thread.java:745)
> 
> Erik Weber wrote:
>     Most likely because the size for the systemvm template (in my case) is 
> zero. Not sure if that is normal or not, but preferably it should be handled 
> somehow

Not sure whether your systemvm template is zero, but everything seems fine with 
me .
Yes some validation with volume size is needed.
Anyone confront the issue?

BTW, i'm using 
http://cloudstack.apt-get.eu/systemvm/4.4/systemvm64template-4.4.1-7-xen.vhd.bz2


- Yitao


-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/29087/#review65190
-----------------------------------------------------------


On 十二月 16, 2014, 6:07 a.m., Yitao Jiang wrote:
> 
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/29087/
> -----------------------------------------------------------
> 
> (Updated 十二月 16, 2014, 6:07 a.m.)
> 
> 
> Review request for cloudstack, Mike Tutkowski and Min Chen.
> 
> 
> Repository: cloudstack-git
> 
> 
> Description
> -------
> 
> Currently, only KVM support root disk resize feature in ACS 4.4.1.
> As we are now using Xenserver 6.2.0, and it's so convient feature,
> So we do some hack.
> 
> 
> Diffs
> -----
> 
>   
> plugins/hypervisors/xen/src/com/cloud/hypervisor/xen/resource/XenServerStorageProcessor.java
>  e326770 
>   server/src/com/cloud/vm/UserVmManagerImpl.java 4fdacd5 
>   ui/scripts/instanceWizard.js 9df6ecc 
> 
> Diff: https://reviews.apache.org/r/29087/diff/
> 
> 
> Testing
> -------
> 
> Mgmt Server : CentOS release 6.4 (Final)
> ACS: 4.4.1
> Primary & Secondary : NFS
> 
> 
> Thanks,
> 
> Yitao Jiang
> 
>

Reply via email to