Short question, i saw this repo : http://packages.shapeblue.com/cloudstack/custom/vmware-diskcontroller/centos/4.5/ do i have to use this one ? because i did install from this one :http://packages.shapeblue.com/cloudstack/main/centos/4.5/
Thanks! Regards, Cristian On 26.01.2016 23:43:54, ilya <ilya.mailing.li...@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: tlother...@datacentrix.co.za >> www.datacentrix.co.za [http://www.datacentrix.co.za/] >> >> >> Serious about performance, passionate about value >> >> >> From: Glenn Wagner [mailto:glenn.wag...@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.wag...@shapeblue.com | t: [mailto:glenn.wag...@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.li...@gmail.com >> [mailto:ilya.mailing.li...@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. >> >> >
