cloudstack-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Cristian Ciobanu" <cristia...@istream.today>
Subject Re: CloudStack and VMware VM deploy issue
Date Tue, 26 Jan 2016 21:53:27 GMT
I will come back in few hours with more details, I will start again from scratch.

Thank you!


Regards,
Cristian
On 26.01.2016 23:43:54, ilya <ilya.mailing.lists@gmail.com> wrote:
Can you post full log to pastebin like site?

Ideally, if you can trim the log before you simulate the error - it
would be great.

do

> /var/log/cloudstack/management/management-server.log

Then similate the issue and upload to http://pastebin.com

Thanks
ilya



On 1/26/16 1:33 PM, Cristian Ciobanu wrote:
> Hi ilya
>
>
> Yes, before deploy i set local storage in "Global Settings" also i set on zone level settings and i removed all disk offering with shared option and added with local storage. ( CS 4.5.2 ShapeBlue)
>
> I did this in CS 4.5.2 i also used this version for KVM with local storage and works. but on VMware i have this issue.
>
>
>
>
> B. I have this error when i try the following task ( CS 4.5.2 ShapeBlue - VMware 5.5 - Local Storage enabled with Basic networking ):
>
> I installed an Windows 2008 from ISO file, after this i tried to create template from ROOT volume, but i get this error : Failed to create templatecreate template from volume exception: Exception: java.lang.NullPointerException
> Message: null
>
>
>
>
> A. I see this error when i try to deploy a vm from default template ( CS 4.5.2 ShapeBlue - VMware 5.5 - Local Storage enabled with Basic networking ):
>
> root@ns52xxxx management]# grep -r job-79 --color
> management-server.log:2016-01-26 06:28:27,608 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (API-Job-Executor-14:ctx-107b2062 job-78 ctx-9e2c938d) Sync job-79 execution on object VmWorkJobQueue.12
> management-server.log:2016-01-26 06:28:29,292 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (AsyncJobMgr-Heartbeat-1:ctx-336c2f61) Schedule queued job-79
> management-server.log:2016-01-26 06:28:29,307 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Add job-79 into job monitoring
> management-server.log:2016-01-26 06:28:29,320 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Executing AsyncJobVO {id:79, userId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.vm.VmWorkStart, cmdInfo: rO0ABXNyABhjb20uY2xvdWQudm0uVm1Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZhdm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAljbHVzdGVySWR0ABBMamF2YS9sYW5nL0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxqYXZhL2xhbmcvU3RyaW5nO0wAEXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVwb2RJZHEAfgACTAAGcG9vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNlcnZhdGlvbklkcQB-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAAACAAAAAAAAAAIAAAAAAAAADHQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAXBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAFxAH4ACnBwcHEAfgAKcHNy
A
BFqYXZhLnV0aWwuSGFzaE1hcAUH2sHDFmDRAwACRgAKbG9hZEZhY3RvckkACXRocmVzaG9sZHhwP0AAAAAAAAx3CAAAABAAAAABdAAKVm1QYXNzd29yZHQAHHJPMEFCWFFBRG5OaGRtVmtYM0JoYzNOM2IzSmt4cA, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 37591044139110, completeMsid: null, lastUpdated: null, lastPolled: null, created: Tue Jan 26 06:28:27 EST 2016}
> management-server.log:2016-01-26 06:28:29,321 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Run VM work job: com.cloud.vm.VmWorkStart for VM 12, job origin: 78
> management-server.log:2016-01-26 06:28:29,323 DEBUG [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Execute VM work job: com.cloud.vm.VmWorkStart{"dcId":1,"podId":1,"clusterId":1,"hostId":1,"rawParams":{"VmPassword":"rO0ABXQADnNhdmVkX3Bhc3N3b3Jk"},"userId":2,"accountId":2,"vmId":12,"handlerName":"VirtualMachineManagerImpl"}
> management-server.log:2016-01-26 06:28:29,334 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) 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
> management-server.log:2016-01-26 06:28:29,334 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Successfully transitioned to start state for VM[User|i-2-12-VM] reservation id = ae2f6b6d-693a-4dc6-8e80-28f1ca7e9777
> management-server.log:2016-01-26 06:28:29,350 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Trying to deploy VM, vm has dcId: 1 and podId: null
> management-server.log:2016-01-26 06:28:29,350 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) advanceStart: DeploymentPlan is provided, using dcId:1, podId: 1, clusterId: 1, hostId: 1, poolId: null
> management-server.log:2016-01-26 06:28:29,350 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Deploy avoids pods: null, clusters: null, hosts: null
> management-server.log:2016-01-26 06:28:29,360 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Deploy avoids pods: [], clusters: [], hosts: []
> management-server.log:2016-01-26 06:28:29,361 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner@46c88784
> management-server.log:2016-01-26 06:28:29,361 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested cpu: 500, requested ram: 536870912
> management-server.log:2016-01-26 06:28:29,361 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Is ROOT volume READY (pool already allocated)?: No
> management-server.log:2016-01-26 06:28:29,361 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) DeploymentPlan has host_id specified, choosing this host and making no checks on this host: 1
> management-server.log:2016-01-26 06:28:29,363 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Looking for suitable pools for this host under zone: 1, pod: 1, cluster: 1
> management-server.log:2016-01-26 06:28:29,366 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Checking suitable pools for volume (Id, Type): (15,ROOT)
> management-server.log:2016-01-26 06:28:29,367 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) We need to allocate new storagepool for this volume
> management-server.log:2016-01-26 06:28:29,368 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Calling StoragePoolAllocators to find suitable pools
> management-server.log:2016-01-26 06:28:29,368 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) LocalStoragePoolAllocator trying to find storage pool to fit the vm
> management-server.log:2016-01-26 06:28:29,371 DEBUG [o.a.c.s.a.AbstractStoragePoolAllocator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Checking if storage pool is suitable, name: null ,poolId: 1
> management-server.log:2016-01-26 06:28:29,373 INFO [c.c.s.StorageManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Storage pool null (1) does not supply IOPS capacity, assuming enough capacity
> management-server.log:2016-01-26 06:28:29,374 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Checking pool 1 for storage, totalSize: 3992708972544, usedBytes: 10096738304, usedPct: 0.002528793952534624, disable threshold: 0.85
> management-server.log:2016-01-26 06:28:29,380 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Found storage pool ns52xxx.ip-1xx-xx-2xx.net Local Storage of type VMFS with overprovisioning factor 1
> management-server.log:2016-01-26 06:28:29,380 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Total over provisioned capacity calculated is 1 * 3992708972544
> management-server.log:2016-01-26 06:28:29,380 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Total capacity of the pool ns52xxx.ip-1xx-xx-2xx.net Local Storage id: 1 is 3992708972544
> management-server.log:2016-01-26 06:28:29,381 DEBUG [c.c.s.StorageManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Checking pool: 1 for volume allocation [Vol[15|vm=12|ROOT]], maxSize : 3992708972544, totalAllocatedSize : 3145728000, askingSize : 2147483648, allocated disable threshold: 0.85
> management-server.log:2016-01-26 06:28:29,382 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Found suitable local storage pool 1, adding to list
> management-server.log:2016-01-26 06:28:29,382 DEBUG [o.a.c.s.a.LocalStoragePoolAllocator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) LocalStoragePoolAllocator returning 1 suitable storage pools
> management-server.log:2016-01-26 06:28:29,382 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Trying to find a potenial host and associated storage pools from the suitable host/pool lists for this VM
> management-server.log:2016-01-26 06:28:29,382 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Checking if host: 1 can access any suitable storage pool for volume: ROOT
> management-server.log:2016-01-26 06:28:29,383 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Host: 1 can access pool: 1
> management-server.log:2016-01-26 06:28:29,383 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Found a potential host id: 1 name: ns52xxx.ip-1xx-xx-2xx.net and associated storage pools for this VM
> management-server.log:2016-01-26 06:28:29,384 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) 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(15|ROOT-->Pool(1))]
> management-server.log:2016-01-26 06:28:29,384 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Deployment found - P0=VM[User|i-2-12-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(15|ROOT-->Pool(1))]
> management-server.log:2016-01-26 06:28:29,408 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) 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
> management-server.log:2016-01-26 06:28:29,415 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Hosts's actual total CPU: 29592 and CPU after applying overprovisioning: 29592
> management-server.log:2016-01-26 06:28:29,415 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) We are allocating VM, increasing the used capacity of this host:1
> management-server.log:2016-01-26 06:28:29,415 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Current Used CPU: 3000 , Free CPU:26592 ,Requested CPU: 500
> management-server.log:2016-01-26 06:28:29,415 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Current Used RAM: 3758096384 , Free RAM:30489239552 ,Requested RAM: 536870912
> management-server.log:2016-01-26 06:28:29,415 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) CPU STATS after allocation: for host: 1, old used: 3000, old reserved: 0, actual total: 29592, total with overprovisioning: 29592; new used:3500, reserved:0; requested cpu:500,alloc_from_last:false
> management-server.log:2016-01-26 06:28:29,415 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) RAM STATS after allocation: for host: 1, old used: 3758096384, old reserved: 0, total: 34247335936; new used: 4294967296, reserved: 0; requested mem: 536870912,alloc_from_last:false
> management-server.log:2016-01-26 06:28:29,432 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) VM is being created in podId: 1
> management-server.log:2016-01-26 06:28:29,440 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Network id=204 is already implemented
> management-server.log:2016-01-26 06:28:29,466 DEBUG [c.c.n.NetworkModelImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Service SecurityGroup is not supported in the network id=204
> management-server.log:2016-01-26 06:28:29,491 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Changing active number of nics for network id=204 on 1
> management-server.log:2016-01-26 06:28:29,510 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Asking VirtualRouter to prepare for Nic[26-12-ae2f6b6d-693a-4dc6-8e80-28f1ca7e9777-1xx.xx.xx.235]
> management-server.log:2016-01-26 06:28:29,521 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) 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(Id))] : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(15|ROOT-->Pool(1))]
> management-server.log:2016-01-26 06:28:29,524 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) 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(Id))] : Dest[Zone(1)-Pod(1)-Cluster(1)-Host(1)-Storage(Volume(15|ROOT-->Pool(1))]
> management-server.log:2016-01-26 06:28:29,549 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Applying dhcp entry in network Ntwk[204|Guest|7]
> management-server.log:2016-01-26 06:28:29,564 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 1-1380071810812347953: Sending { Cmd , MgmtId: 37591044139110, via: 1(ns52xxx.ip-1xx-xx-2xx.net), Ver: v1, Flags: 100011, [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:80:4c:00:00:3b","vmIpAddress":"1xx.xx.xx.235","vmName":"TST11","defaultRouter":"1xx.xx.xx.238","defaultDns":"1xx.xx.xx.230","duid":"00:03:00:01:06:80:4c:00:00:3b","isDefault":true,"executeInSequence":false,"accessDetails":{"zone.network.type":"Basic","router.guest.ip":"1xx.xx.xx.230","router.ip":"0.0.0.0","router.name":"r-11-VM"},"wait":0}}] }
> management-server.log:2016-01-26 06:28:29,565 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 1-1380071810812347953: Executing: { Cmd , MgmtId: 37591044139110, via: 1(ns52xxx.ip-1xx-xx-2xx.net), Ver: v1, Flags: 100011, [{"com.cloud.agent.api.routing.DhcpEntryCommand":{"vmMac":"06:80:4c:00:00:3b","vmIpAddress":"1xx.xx.xx.235","vmName":"TST11","defaultRouter":"1xx.xx.xx.238","defaultDns":"1xx.xx.xx.230","duid":"00:03:00:01:06:80:4c:00:00:3b","isDefault":true,"executeInSequence":false,"accessDetails":{"zone.network.type":"Basic","router.guest.ip":"1xx.xx.xx.230","router.ip":"0.0.0.0","router.name":"r-11-VM"},"wait":0}}] }
> management-server.log:2016-01-26 06:28:29,565 DEBUG [c.c.h.v.r.VmwareResource] (DirectAgent-37:ctx-233e6307 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: DhcpEntryCommand) In Basic zone mode, use router's guest IP for SSH control. guest IP : 1xx.xx.xx.230
> management-server.log:2016-01-26 06:28:29,566 DEBUG [c.c.h.v.r.VmwareResource] (DirectAgent-37:ctx-233e6307 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: DhcpEntryCommand) Run command on VR: 1xx.xx.xx.230, script: edithosts.sh with args: -m 06:80:4c:00:00:3b -4 1xx.xx.xx.235 -h TST11 -d 1xx.xx.xx.238 -n 1xx.xx.xx.230
> management-server.log:2016-01-26 06:28:29,902 DEBUG [c.c.h.v.r.VmwareResource] (DirectAgent-37:ctx-233e6307 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: DhcpEntryCommand) edithosts.sh execution result: true
> management-server.log:2016-01-26 06:28:29,904 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 1-1380071810812347953: Received: { Ans: , MgmtId: 37591044139110, via: 1, Ver: v1, Flags: 10, { Answer } }
> management-server.log:2016-01-26 06:28:29,917 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Applying userdata and password entry in network Ntwk[204|Guest|7]
> management-server.log:2016-01-26 06:28:29,940 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 1-1380071810812347954: Sending { Cmd , MgmtId: 37591044139110, via: 1(ns52xxx.ip-1xx-xx-2xx.net), Ver: v1, Flags: 100011, [{"com.cloud.agent.api.routing.SavePasswordCommand":{,"vmIpAddress":"1xx.xx.xx.235","vmName":"TST11","executeInSequence":false,"accessDetails":{"zone.network.type":"Basic","router.name":"r-11-VM","router.ip":"0.0.0.0","router.guest.ip":"1xx.xx.xx.230"},"wait":0}},{"com.cloud.agent.api.routing.VmDataCommand":{"vmIpAddress":"1xx.xx.xx.235","vmName":"TST11","executeInSequence":false,"accessDetails":{"zone.network.type":"Basic","router.name":"r-11-VM","router.ip":"0.0.0.0","router.guest.ip":"1xx.xx.xx.230"},"wait":0}}] }
> management-server.log:2016-01-26 06:28:29,943 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 1-1380071810812347954: Executing: { Cmd , MgmtId: 37591044139110, via: 1(ns52xxx.ip-1xx-xx-2xx.net), Ver: v1, Flags: 100011, [{"com.cloud.agent.api.routing.SavePasswordCommand":{,"vmIpAddress":"1xx.xx.xx.235","vmName":"TST11","executeInSequence":false,"accessDetails":{"zone.network.type":"Basic","router.name":"r-11-VM","router.ip":"0.0.0.0","router.guest.ip":"1xx.xx.xx.230"},"wait":0}},{"com.cloud.agent.api.routing.VmDataCommand":{"vmIpAddress":"1xx.xx.xx.235","vmName":"TST11","executeInSequence":false,"accessDetails":{"zone.network.type":"Basic","router.name":"r-11-VM","router.ip":"0.0.0.0","router.guest.ip":"1xx.xx.xx.230"},"wait":0}}] }
> management-server.log:2016-01-26 06:28:29,944 DEBUG [c.c.h.v.r.VmwareResource] (DirectAgent-293:ctx-7bf50e13 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: SavePasswordCommand) In Basic zone mode, use router's guest IP for SSH control. guest IP : 1xx.xx.xx.230
> management-server.log:2016-01-26 06:28:29,944 DEBUG [c.c.h.v.r.VmwareResource] (DirectAgent-293:ctx-7bf50e13 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: SavePasswordCommand) Run command on VR: 1xx.xx.xx.230, script: savepassword.sh with args: -v 1xx.xx.xx.235 -p saved_password
> management-server.log:2016-01-26 06:28:30,220 DEBUG [c.c.h.v.r.VmwareResource] (DirectAgent-293:ctx-7bf50e13 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: SavePasswordCommand) savepassword.sh execution result: true
> management-server.log:2016-01-26 06:28:30,222 DEBUG [c.c.h.v.r.VmwareResource] (DirectAgent-293:ctx-7bf50e13 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: VmDataCommand) In Basic zone mode, use router's guest IP for SSH control. guest IP : 1xx.xx.xx.230
> management-server.log:2016-01-26 06:28:30,223 DEBUG [c.c.h.v.r.VmwareResource] (DirectAgent-293:ctx-7bf50e13 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: VmDataCommand) Run command on VR: 1xx.xx.xx.230, script: vmdata.py with args: -d eyIxNTguNjkuMTcuMjM1IjpbWyJ1c2VyZGF0YSIsInVzZXItZGF0YSIsbnVsbF0sWyJtZXRhZGF0YSIsInNlcnZpY2Utb2ZmZXJpbmciLCJTbWFsbCBJbnN0YW5jZSJdLFsibWV0YWRhdGEiLCJhdmFpbGFiaWxpdHktem9uZSIsIlBPRDAwMSJdLFsibWV0YWRhdGEiLCJsb2NhbC1pcHY0IiwiMTU4LjY5LjE3LjIzNSJdLFsibWV0YWRhdGEiLCJsb2NhbC1ob3N0bmFtZSIsIlRTVDExIl0sWyJtZXRhZGF0YSIsInB1YmxpYy1pcHY0IiwiMTU4LjY5LjE3LjIzNSJdLFsibWV0YWRhdGEiLCJwdWJsaWMtaG9zdG5hbWUiLCJUU1QxMSJdLFsibWV0YWRhdGEiLCJpbnN0YW5jZS1pZCIsIjhkMmI5NzVlLTYyNWItNDI0Mi04YTg2LTNjMTA0MzkzYmIyMCJdLFsibWV0YWRhdGEiLCJ2bS1pZCIsIjhkMmI5NzVlLTYyNWItNDI0Mi04YTg2LTNjMTA0MzkzYmIyMCJdLFsibWV0YWRhdGEiLCJwdWJsaWMta2V5cyIsbnVsbF0sWyJtZXRhZGF0YSIsImNsb3VkLWlkZW50aWZpZXIiLCJDbG91ZFN0YWNrLXtiNWE2M2FlMi1jOWU0LTQ2YmMtODA3ZS03NjkwMWM0ZTNlOGV9Il1dfQ==
> management-server.log:2016-01-26 06:28:30,447 DEBUG [c.c.h.v.r.VmwareResource] (DirectAgent-293:ctx-7bf50e13 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: VmDataCommand) vmdata.py execution result: true
> management-server.log:2016-01-26 06:28:30,449 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 1-1380071810812347954: Received: { Ans: , MgmtId: 37591044139110, via: 1, Ver: v1, Flags: 10, { Answer, Answer } }
> management-server.log:2016-01-26 06:28:30,450 DEBUG [c.c.n.NetworkModelImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Service SecurityGroup is not supported in the network id=204
> management-server.log:2016-01-26 06:28:30,453 DEBUG [o.a.c.e.o.VolumeOrchestrator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Checking if we need to prepare 1 volumes for VM[User|i-2-12-VM]
> management-server.log:2016-01-26 06:28:30,465 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) template 7 is already in store:1, type:Image
> management-server.log:2016-01-26 06:28:30,469 DEBUG [o.a.c.s.d.PrimaryDataStoreImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Not found (templateId:7poolId:1) in template_spool_ref, persisting it
> management-server.log:2016-01-26 06:28:30,493 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) template 7 is already in store:1, type:Primary
> management-server.log:2016-01-26 06:28:30,495 DEBUG [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Found template centos53-x64 in storage pool 1 with VMTemplateStoragePool id: 15
> management-server.log:2016-01-26 06:28:30,496 DEBUG [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Acquire lock on VMTemplateStoragePool 15 with timeout 3600 seconds
> management-server.log:2016-01-26 06:28:30,498 INFO [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) lock is acquired for VMTemplateStoragePool 15
> management-server.log:2016-01-26 06:28:30,517 DEBUG [o.a.c.s.m.AncientDataMotionStrategy] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) copyAsync inspecting src type TEMPLATE copyAsync inspecting dest type TEMPLATE
> management-server.log:2016-01-26 06:28:30,557 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 3-5184769071010284298: Sending { Cmd , MgmtId: 37591044139110, via: 3(s-4-VM), Ver: v1, Flags: 100111, [{"org.apache.cloudstack.storage.command.CopyCommand":{"srcTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"path":"template/tmpl/1/7/34da6edf-8871-3509-9fad-9001d7f9575a.ova","origUrl":"http://download.cloud.com/releases/2.2.0/CentOS5.3-x86_64.ova [http://download.cloud.com/releases/2.2.0/CentOS5.3-x86_64.ova]","uuid":"f164ac64-c393-11e5-8b5b-0cc47a7d402a","id":7,"format":"OVA","accountId":1,"checksum":"f6f881b7f2292948d8494db837fe0f47","hvm":false,"displayText":"CentOS 5.3(64-bit) no GUI (vSphere)","imageDataStore":{"com.cloud.agent.api.to.NfsTO":{"_url":"nfs://172.11.0.2/secondary","_role":"Image"}},"name":"centos53-x64","hypervisorType":"VMware"}},"destTO":{"org.apache.cloudstack.storage.to.TemplateObjectTO":{"origUrl":"http://downl
o
ad.cloud.com/releases/2.2.0/CentOS5.3-x86_64.ova [http://download.cloud.com/releases/2.2.0/CentOS5.3-x86_64.ova]","uuid":"f164ac64-c393-11e5-8b5b-0cc47a7d402a","id":7,"format":"OVA","accountId":1,"checksum":"f6f881b7f2292948d8494db837fe0f47","hvm":false,"displayText":"CentOS 5.3(64-bit) no GUI (vSphere)","imageDataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"39f5d196-1569-41a7-996c-06abb1325d23","id":1,"poolType":"VMFS","host":"VMFS datastore: datastore-159","path":"datastore-159","port":0,"url":"VMFS://VMFS datastore: datastore-159/datastore-159/?ROLE=Primary&STOREUUID=39f5d196-1569-41a7-996c-06abb1325d23"}},"name":"centos53-x64","hypervisorType":"VMware"}},"executeInSequence":true,"options":{},"wait":10800}}] }
> management-server.log:2016-01-26 06:29:33,767 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 3-5184769071010284298: Received: { Ans: , MgmtId: 37591044139110, via: 3, Ver: v1, Flags: 110, { CopyCmdAnswer } }
> management-server.log:2016-01-26 06:29:33,819 INFO [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) releasing lock for VMTemplateStoragePool 15
> management-server.log:2016-01-26 06:29:33,821 DEBUG [o.a.c.e.o.VolumeOrchestrator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Unable to create Vol[15|vm=12|ROOT]:Failed to create a Vmware context, check the management server logs or the ssvm log for details
> management-server.log:2016-01-26 06:29:33,821 INFO [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Unable to contact resource.
> management-server.log:2016-01-26 06:29:33,836 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Cleaning up resources for the vm VM[User|i-2-12-VM] in Starting state
> management-server.log:2016-01-26 06:29:33,841 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 1-1380071810812347957: Sending { Cmd , MgmtId: 37591044139110, via: 1(ns52xxx.ip-1xx-xx-2xx.net), Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"checkBeforeCleanup":false,"vmName":"i-2-12-VM","wait":0}}] }
> management-server.log:2016-01-26 06:29:33,841 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 1-1380071810812347957: Executing: { Cmd , MgmtId: 37591044139110, via: 1(ns52xxx.ip-1xx-xx-2xx.net), Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"checkBeforeCleanup":false,"vmName":"i-2-12-VM","wait":0}}] }
> management-server.log:2016-01-26 06:29:33,842 INFO [c.c.h.v.r.VmwareResource] (DirectAgent-74:ctx-e2f53743 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: StopCommand) Executing resource StopCommand: {"isProxy":false,"executeInSequence":true,"checkBeforeCleanup":false,"vmName":"i-2-12-VM","wait":0}
> management-server.log:2016-01-26 06:29:33,867 DEBUG [c.c.h.v.m.HostMO] (DirectAgent-74:ctx-e2f53743 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: StopCommand) find VM i-2-12-VM on host
> management-server.log:2016-01-26 06:29:33,867 INFO [c.c.h.v.m.HostMO] (DirectAgent-74:ctx-e2f53743 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: StopCommand) VM i-2-12-VM not found in host cache
> management-server.log:2016-01-26 06:29:33,867 DEBUG [c.c.h.v.m.HostMO] (DirectAgent-74:ctx-e2f53743 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: StopCommand) load VM cache on host
> management-server.log:2016-01-26 06:29:33,896 INFO [c.c.h.v.r.VmwareResource] (DirectAgent-74:ctx-e2f53743 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: StopCommand) VM i-2-12-VM is no longer in vSphere
> management-server.log:2016-01-26 06:29:33,897 DEBUG [c.c.a.t.Request] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Seq 1-1380071810812347957: Received: { Ans: , MgmtId: 37591044139110, via: 1, Ver: v1, Flags: 110, { StopAnswer } }
> management-server.log:2016-01-26 06:29:33,908 DEBUG [c.c.n.NetworkModelImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Service SecurityGroup is not supported in the network id=204
> management-server.log:2016-01-26 06:29:33,911 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Changing active number of nics for network id=204 on -1
> management-server.log:2016-01-26 06:29:33,945 DEBUG [o.a.c.e.o.NetworkOrchestrator] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Asking VirtualRouter to release NicProfile[26-12-ae2f6b6d-693a-4dc6-8e80-28f1ca7e9777-1xx.xx.xx.235-vlan://untagged
> management-server.log:2016-01-26 06:29:33,946 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Successfully released network resources for the vm VM[User|i-2-12-VM]
> management-server.log:2016-01-26 06:29:33,946 DEBUG [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Successfully cleanued up resources for the vm VM[User|i-2-12-VM] in Starting state
> management-server.log:2016-01-26 06:29:33,956 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Deploy avoids pods: [], clusters: [], hosts: [1]
> management-server.log:2016-01-26 06:29:33,957 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner@46c88784
> management-server.log:2016-01-26 06:29:33,957 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested cpu: 500, requested ram: 536870912
> management-server.log:2016-01-26 06:29:33,957 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Is ROOT volume READY (pool already allocated)?: No
> management-server.log:2016-01-26 06:29:33,957 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) DeploymentPlan has host_id specified, choosing this host and making no checks on this host: 1
> management-server.log:2016-01-26 06:29:33,958 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) The specified host is in avoid set
> management-server.log:2016-01-26 06:29:33,959 DEBUG [c.c.d.DeploymentPlanningManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Cannnot deploy to specified host, returning.
> management-server.log:2016-01-26 06:29:34,012 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) VM state transitted from :Starting to Stopped with event: OperationFailedvm's original host id: null new host id: null host id before state transition: 1
> management-server.log:2016-01-26 06:29:34,020 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Hosts's actual total CPU: 29592 and CPU after applying overprovisioning: 29592
> management-server.log:2016-01-26 06:29:34,020 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Hosts's actual total RAM: 34247335936 and RAM after applying overprovisioning: 34247335936
> management-server.log:2016-01-26 06:29:34,020 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) release cpu from host: 1, old used: 3500,reserved: 0, actual total: 29592, total with overprovisioning: 29592; new used: 3000,reserved:0; movedfromreserved: false,moveToReserveredfalse
> management-server.log:2016-01-26 06:29:34,020 DEBUG [c.c.c.CapacityManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) release mem from host: 1, old used: 4294967296,reserved: 0, total: 34247335936; new used: 3758096384,reserved:0; movedfromreserved: false,moveToReserveredfalse
> management-server.log:2016-01-26 06:29:34,079 ERROR [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Invocation exception, caused by: com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|i-2-12-VM]Scope=interface com.cloud.dc.DataCenter; id=1
> management-server.log:2016-01-26 06:29:34,079 INFO [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Rethrow exception com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|i-2-12-VM]Scope=interface com.cloud.dc.DataCenter; id=1
> management-server.log:2016-01-26 06:29:34,080 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Done with run of VM work job: com.cloud.vm.VmWorkStart for VM 12, job origin: 78
> management-server.log:2016-01-26 06:29:34,080 ERROR [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Unable to complete AsyncJobVO {id:79, userId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.vm.VmWorkStart, cmdInfo: rO0ABXNyABhjb20uY2xvdWQudm0uVm1Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZhdm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAljbHVzdGVySWR0ABBMamF2YS9sYW5nL0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxqYXZhL2xhbmcvU3RyaW5nO0wAEXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVwb2RJZHEAfgACTAAGcG9vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNlcnZhdGlvbklkcQB-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAAACAAAAAAAAAAIAAAAAAAAADHQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAXBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAFxAH4ACnBwcHEAfgAKc
H
NyABFqYXZhLnV0aWwuSGFzaE1hcAUH2sHDFmDRAwACRgAKbG9hZEZhY3RvckkACXRocmVzaG9sZHhwP0AAAAAAAAx3CAAAABAAAAABdAAKVm1QYXNzd29yZHQAHHJPMEFCWFFBRG5OaGRtVmtYM0JoYzNOM2IzSmt4cA, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 37591044139110, completeMsid: null, lastUpdated: null, lastPolled: null, created: Tue Jan 26 06:28:27 EST 2016}, job origin:78
> management-server.log:2016-01-26 06:29:34,083 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Complete async job-79, jobStatus: FAILED, resultCode: 0, result: rO0ABXNyADdjb20uY2xvdWQuZXhjZXB0aW9uLkluc3VmZmljaWVudFNlcnZlckNhcGFjaXR5RXhjZXB0aW9uAAAAAFZNT3ACAAFaABVhZmZpbml0eUdyb3Vwc0FwcGxpZWR4cgAxY29tLmNsb3VkLmV4Y2VwdGlvbi5JbnN1ZmZpY2llbnRDYXBhY2l0eUV4Y2VwdGlvbgAAAABWTU91AgACTAACaWR0ABBMamF2YS9sYW5nL0xvbmc7TAAFc2NvcGV0ABFMamF2YS9sYW5nL0NsYXNzO3hyACJjb20uY2xvdWQuZXhjZXB0aW9uLkNsb3VkRXhjZXB0aW9ueeiOG_HkRMcCAAJMAAtjc0Vycm9yQ29kZXQAE0xqYXZhL2xhbmcvSW50ZWdlcjtMAAZpZExpc3R0ABVMamF2YS91dGlsL0FycmF5TGlzdDt4cgATamF2YS5sYW5nLkV4Y2VwdGlvbtD9Hz4aOxzEAgAAeHIAE2phdmEubGFuZy5UaHJvd2FibGXVxjUnOXe4ywMABEwABWNhdXNldAAVTGphdmEvbGFuZy9UaHJvd2FibGU7TAANZGV0YWlsTWVzc2FnZXQAEkxqYXZhL2xhbmcvU3RyaW5nO1sACnN0YWNrVHJhY2V0AB5bTGphdmEvbGFuZy9TdGFja1RyYWNlRWxlbWVudDtMABRzdXBwcmVzc2VkRXhjZXB0aW9uc3QAEExqYXZhL3V0aWwvTGlzdDt4cHEAfgANdAA0VW5hYmxlIHRvIGNyZWF0ZSBhIGRlcGxveW1lbnQ
g
Zm9yIFZNW1VzZXJ8aS0yLTEyLVZNXXVyAB5bTGphdmEubGFuZy5TdGFja1RyYWNlRWxlbWVudDsCRio8PP0iOQIAAHhwAAAAFXNyABtqYXZhLmxhbmcuU3RhY2tUcmFjZUVsZW1lbnRhCcWaJjbdhQIABEkACmxpbmVOdW1iZXJMAA5kZWNsYXJpbmdDbGFzc3EAfgAKTAAIZmlsZU5hbWVxAH4ACkwACm1ldGhvZE5hbWVxAH4ACnhwAAADs3QAJmNvbS5jbG91ZC52bS5WaXJ0dWFsTWFjaGluZU1hbmFnZXJJbXBsdAAeVmlydHVhbE1hY2hpbmVNYW5hZ2VySW1wbC5qYXZhdAAQb3JjaGVzdHJhdGVTdGFydHNxAH4AEQAAEYFxAH4AE3EAfgAUcQB-ABVzcQB-ABH____-dAAkc3VuLnJlZmxlY3QuTmF0aXZlTWV0aG9kQWNjZXNzb3JJbXBsdAAdTmF0aXZlTWV0aG9kQWNjZXNzb3JJbXBsLmphdmF0AAdpbnZva2Uwc3EAfgARAAAAOXEAfgAYcQB-ABl0AAZpbnZva2VzcQB-ABEAAAArdAAoc3VuLnJlZmxlY3QuRGVsZWdhdGluZ01ldGhvZEFjY2Vzc29ySW1wbHQAIURlbGVnYXRpbmdNZXRob2RBY2Nlc3NvckltcGwuamF2YXEAfgAcc3EAfgARAAACXnQAGGphdmEubGFuZy5yZWZsZWN0Lk1ldGhvZHQAC01ldGhvZC5qYXZhcQB-ABxzcQB-ABEAAABrdAAiY29tLmNsb3VkLnZtLlZtV29ya0pvYkhhbmRsZXJQcm94eXQAGlZtV29ya0pvYkhhbmRsZXJQcm94eS5qYXZhdAAPaGFuZGxlVm1Xb3JrSm9ic3EAfgARAAASHXEAfgATcQB-ABRxAH4AJnNxAH4AEQAAAGd0ACBjb20uY2xvdWQudm0uVm1Xb3JrSm9iRGlzcGF0Y2hlcnQAGFZtV2
9
ya0pvYkRpc3BhdGNoZXIuamF2YXQABnJ1bkpvYnNxAH4AEQAAAhl0AD9vcmcuYXBhY2hlLmNsb3Vkc3RhY2suZnJhbWV3b3JrLmpvYnMuaW1wbC5Bc3luY0pvYk1hbmFnZXJJbXBsJDV0ABhBc3luY0pvYk1hbmFnZXJJbXBsLmphdmF0AAxydW5JbkNvbnRleHRzcQB-ABEAAAAxdAA-b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlJDF0ABtNYW5hZ2VkQ29udGV4dFJ1bm5hYmxlLmphdmF0AANydW5zcQB-ABEAAAA4dABCb3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5pbXBsLkRlZmF1bHRNYW5hZ2VkQ29udGV4dCQxdAAaRGVmYXVsdE1hbmFnZWRDb250ZXh0LmphdmF0AARjYWxsc3EAfgARAAAAZ3QAQG9yZy5hcGFjaGUuY2xvdWRzdGFjay5tYW5hZ2VkLmNvbnRleHQuaW1wbC5EZWZhdWx0TWFuYWdlZENvbnRleHRxAH4ANnQAD2NhbGxXaXRoQ29udGV4dHNxAH4AEQAAADVxAH4AOXEAfgA2dAAOcnVuV2l0aENvbnRleHRzcQB-ABEAAAAudAA8b3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5NYW5hZ2VkQ29udGV4dFJ1bm5hYmxlcQB-ADJxAH4AM3NxAH4AEQAAAe5xAH4ALXEAfgAucQB-ADNzcQB-ABEAAAHXdAAuamF2YS51dGlsLmNvbmN1cnJlbnQuRXhlY3V0b3JzJFJ1bm5hYmxlQWRhcHRlcnQADkV4ZWN1dG9ycy5qYXZhcQB-ADdzcQB-ABEAAAEGdAAfamF2YS51dGlsLmNvbmN1cnJlbnQuRnV0dXJlVGFza3QAD0Z1d
H
VyZVRhc2suamF2YXEAfgAzc3EAfgARAAAEeXQAJ2phdmEudXRpbC5jb25jdXJyZW50LlRocmVhZFBvb2xFeGVjdXRvcnQAF1RocmVhZFBvb2xFeGVjdXRvci5qYXZhdAAJcnVuV29ya2Vyc3EAfgARAAACZ3QALmphdmEudXRpbC5jb25jdXJyZW50LlRocmVhZFBvb2xFeGVjdXRvciRXb3JrZXJxAH4ASHEAfgAzc3EAfgARAAAC6XQAEGphdmEubGFuZy5UaHJlYWR0AAtUaHJlYWQuamF2YXEAfgAzc3IAJmphdmEudXRpbC5Db2xsZWN0aW9ucyRVbm1vZGlmaWFibGVMaXN0_A8lMbXsjhACAAFMAARsaXN0cQB-AAx4cgAsamF2YS51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJsZUNvbGxlY3Rpb24ZQgCAy173HgIAAUwAAWN0ABZMamF2YS91dGlsL0NvbGxlY3Rpb247eHBzcgATamF2YS51dGlsLkFycmF5TGlzdHiB0h2Zx2GdAwABSQAEc2l6ZXhwAAAAAHcEAAAAAHhxAH4AVHhzcgARamF2YS5sYW5nLkludGVnZXIS4qCk94GHOAIAAUkABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAQ73NxAH4AUwAAAAB3BAAAAAB4c3IADmphdmEubGFuZy5Mb25nO4vkkMyPI98CAAFKAAV2YWx1ZXhxAH4AVgAAAAAAAAABdnIAF2NvbS5jbG91ZC5kYy5EYXRhQ2VudGVysvEK66fneL4CAAB4cAA
> management-server.log:2016-01-26 06:29:34,084 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Publish async job-79 complete on message bus
> management-server.log:2016-01-26 06:29:34,084 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Wake up jobs related to job-79
> management-server.log:2016-01-26 06:29:34,085 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Update db status for job-79
> management-server.log:2016-01-26 06:29:34,087 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Wake up jobs joined with job-79 and disjoin all subjobs created from job- 79
> management-server.log:2016-01-26 06:29:34,111 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Done executing com.cloud.vm.VmWorkStart for job-79
> management-server.log:2016-01-26 06:29:34,113 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Remove job-79 from job monitoring
> catalina.out:INFO [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Add job-79 into job monitoring
> catalina.out:INFO [c.c.s.StorageManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Storage pool null (1) does not supply IOPS capacity, assuming enough capacity
> catalina.out:INFO [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) lock is acquired for VMTemplateStoragePool 15
> catalina.out:INFO [o.a.c.s.v.VolumeServiceImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) releasing lock for VMTemplateStoragePool 15
> catalina.out:INFO [c.c.v.VirtualMachineManagerImpl] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Unable to contact resource.
> catalina.out:INFO [c.c.h.v.r.VmwareResource] (DirectAgent-74:ctx-e2f53743 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: StopCommand) Executing resource StopCommand: {"isProxy":false,"executeInSequence":true,"checkBeforeCleanup":false,"vmName":"i-2-12-VM","wait":0}
> catalina.out:INFO [c.c.h.v.m.HostMO] (DirectAgent-74:ctx-e2f53743 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: StopCommand) VM i-2-12-VM not found in host cache
> catalina.out:INFO [c.c.h.v.r.VmwareResource] (DirectAgent-74:ctx-e2f53743 ns52xxx.ip-1xx-xx-2xx.net, job-78/job-79, cmd: StopCommand) VM i-2-12-VM is no longer in vSphere
> catalina.out:ERROR [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Invocation exception, caused by: com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|i-2-12-VM]Scope=interface com.cloud.dc.DataCenter; id=1
> catalina.out:INFO [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79 ctx-67168166) Rethrow exception com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|i-2-12-VM]Scope=interface com.cloud.dc.DataCenter; id=1
> catalina.out:ERROR [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Unable to complete AsyncJobVO {id:79, userId: 2, accountId: 2, instanceType: null, instanceId: null, cmd: com.cloud.vm.VmWorkStart, cmdInfo: rO0ABXNyABhjb20uY2xvdWQudm0uVm1Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZhdm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAljbHVzdGVySWR0ABBMamF2YS9sYW5nL0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxqYXZhL2xhbmcvU3RyaW5nO0wAEXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVwb2RJZHEAfgACTAAGcG9vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNlcnZhdGlvbklkcQB-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAAACAAAAAAAAAAIAAAAAAAAADHQAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAXBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAFxAH4ACnBwcHEAfgAKcHNyABFqYXZhLnV0aWwuSGFzaE1hcAUH2s
H
DFmDRAwACRgAKbG9hZEZhY3RvckkACXRocmVzaG9sZHhwP0AAAAAAAAx3CAAAABAAAAABdAAKVm1QYXNzd29yZHQAHHJPMEFCWFFBRG5OaGRtVmtYM0JoYzNOM2IzSmt4cA, cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result: null, initMsid: 37591044139110, completeMsid: null, lastUpdated: null, lastPolled: null, created: Tue Jan 26 06:28:27 EST 2016}, job origin:78
> catalina.out:INFO [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-Executor-25:ctx-7bfca42f job-78/job-79) Remove job-79 from job monitoring
>
> ============================================================================================
>
> I just want to mention that i test also CS 4.6 and 4.7 but there i have other issue regarding Virtual Router, never start ( i just see "starting" for VR status )
>
> Thank you
>
> Regards,
> Cristian
> On 26.01.2016 23:08:00, ilya wrote:
> Cristian
>
> Not entire clear on strange part.
>
> If you want to use local storage, you must have all components reference
> local storage.
>
> That includes SystemVMs - there is a zone level setting that enables
> local storage, as well as service and disk offering must be set to local.
>
> Regards
> ilya
>
> On 1/25/16 11:51 PM, Cristian Ciobanu wrote:
>> Hi Timothy
>>
>> This is very strange, I configured for local storage also i removed all disk offering and create the same but with local storage.
>>
>>
>>
>> Thank you!
>>
>> Regards,
>> Cristian
>> On 26.01.2016 09:11:35, Timothy Lothering wrote:
>> Hi Cristian,
>>
>> As per Ilya’s mail, check that the DATA disk offering is set to use “Storage Type – Local”, the logs provided indicate that no shared storage can be found:
>>
>> “No storage pools available for shared volume allocation, returning”
>>
>> Change this under the Service Offering à Disk Offering.
>>
>> Regards,
>>
>> [www.datacentrix.co.za] [http://www.datacentrix.co.za]
>> Timothy Lothering
>> Solutions Architect
>> Managed Services
>> T: +27877415535
>> F: +27877415100
>> C: +27824904099
>> E: tlothering@datacentrix.co.za
>> www.datacentrix.co.za [http://www.datacentrix.co.za/]
>>
>>
>> Serious about performance, passionate about value
>>
>>
>> From: Glenn Wagner [mailto:glenn.wagner@shapeblue.com]
>> Sent: Tuesday, 26 January 2016 7:27 AM
>> To: users@cloudstack.apache.org
>> Subject: RE: CloudStack and VMware VM deploy issue
>>
>> Hi Cristian,
>>
>> Like ilya said try create a new VM without a Data Drive when using the Wizard
>> And check that your disk offerings are all set to local storage.
>>
>> If you are just testing you can also change your over provision factor in the Global settings from 2 to like 4 or 6 for storage
>>
>> Error
>> No suitable pools found for volume: Vol[8|vm=7|DATADISK] under cluster: 1
>>
>> Thanks
>> Glenn
>>
>>
>>
>> [ShapeBlue] [http://www.shapeblue.com]
>> Glenn Wagner
>> Senior Consultant
>> ,
>> ShapeBlue
>> d:
>> | s: +27 21 527 0091 [tel:|%20s:%20+27%2021%20527%200091]
>> |
>> m:
>> +27 73 917 4111 [tel:+27%2073%20917%204111]
>> e:
>> glenn.wagner@shapeblue.com | t: [mailto:glenn.wagner@shapeblue.com%20|%20t:]
>> |
>> w:
>> www.shapeblue.com [http://www.shapeblue.com]
>> a:
>> 2nd Floor, Oudehuis Centre, 122 Main Rd, Somerset West Cape Town 7130 South Africa
>> Shape Blue Ltd is a company incorporated in England & Wales. ShapeBlue Services India LLP is a company incorporated in India and is operated under license from Shape Blue Ltd. Shape Blue Brasil Consultoria Ltda is a company incorporated in Brasil and is operated under license from Shape Blue Ltd. ShapeBlue SA Pty Ltd is a company registered by The Republic of South Africa and is traded under license from Shape Blue Ltd. ShapeBlue is a registered trademark.
>> This email and any attachments to it may be confidential and are intended solely for the use of the individual to whom it is addressed. Any views or opinions expressed are solely those of the author and do not necessarily represent those of Shape Blue Ltd or related companies. If you are not the intended recipient of this email, you must neither take any action based upon its contents, nor copy or show it to anyone. Please contact the sender if you believe you have received this email in error.
>>
>> -----Original Message-----
>> From: ilya [mailto:ilya.mailing.lists@gmail.com [mailto:ilya.mailing.lists@gmail.com]]
>> Sent: Tuesday, 26 January 2016 1:29 AM
>> To: users@cloudstack.apache.org [mailto:users@cloudstack.apache.org]
>> Subject: Re: CloudStack and VMware VM deploy issue
>>
>> Cristian
>>
>> See response in-line
>> On 1/25/16 11:51 AM, Cristian Ciobanu wrote:
>>> management-server.log:2016-01-25 14:32:45,743 DEBUG
>>> [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-9:ctx-0c67fce8
>>> job-33 ctx-480f2e68) No suitable pools found for volume:
>>> Vol[8|vm=7|DATADISK] under cluster: 1
>>> management-server.log:2016-01-25 14:32:45,743 DEBUG
>>> [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-9:ctx-0c67fce8
>>> job-33 ctx-480f2e68) No suitable pools found
>>> management-server.log:2016-01-25 14:32:45,743 DEBUG
>>> [c.c.d.DeploymentPlanningManagerImpl] (API-Job-Executor-9:ctx-0c67fce8
>>> job-33 ctx-480f2e68) No suitable storagePools found under this
>>> Cluster: 1
>>
>> Can you deploy without a data disk? That is when going through wizard, dont select any additional disks..
>>
>> If it works, make sure the disk offering you create is also type "local"
>> and not shared or clustered.
>>
>> PS: I would also change a value in global settings for vm.instancename.flag to true. This is going to use the names given in cloudstack in vmware, vs using the pre-generated "i-xxx-xxxx" name. But this has nothing to do with your issue above.
>>
>> Regards
>> ilya
>> Find out more about ShapeBlue and our range of CloudStack related services:
>> IaaS Cloud Design & Build [http://shapeblue.com/iaas-cloud-design-and-build/] | CSForge – rapid IaaS deployment framework [http://shapeblue.com/csforge/]
>> CloudStack Consulting [http://shapeblue.com/cloudstack-consultancy/] | CloudStack Software Engineering [http://shapeblue.com/cloudstack-software-engineering/]
>> CloudStack Infrastructure Support [http://shapeblue.com/cloudstack-infrastructure-support/] | CloudStack Bootcamp Training Courses [http://shapeblue.com/cloudstack-training/]
>>
>> [https://mail28.mimecast.co.za/mimecast/click?account=CSA14A19&code=938ed3adfac8855ae916afa117d73328]
>> Disclaimer
>> Everything in this e-mail and any attachments relating to the official business of Datacentrix Holdings Ltd. and its subsidiaries ('Datacentrix') is proprietary to Datacentrix. It is confidential, legally privileged and protected by law. Datacentrix does not own and endorse any other content. Views and opinions are those of the sender unless clearly stated as being that of Datacentrix. The person addressed in the e-mail is the sole authorised recipient. Please notify the sender immediately if it has unintentionally reached you and do not read, disclose or use the content in any way. Datacentrix cannot assure that the integrity of this communication has been maintained nor that it is free of errors, virus, interception or interference.
>>
>>
>

Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message