I got the following error when running the CloudPlatform™ installation wizard:
Something went wrong; you may go back and correct any errors:
Incorrect number
Hi,
I'm stuck in a problem.
i try to upload an iso via http and its failed. so the iso is unavailable and error status: no route to host.
if i try to delete the broken iso return an error and cannot be deleted
Messages:
Failed to delete iso
In event
Error while deleting iso. ISO Id: 210
Hello,
Now i have uploaded 3 templates it went fine.
now my 4th template, that webnt wrong.
I get errors in the cloudstack-management.log and cataline.out, i do not know if this is related.
from the computer i am using as a webdav server to be able to upload the template, i can see traffic,
for app. 0,5-1 hours 500Mbit/s the template is 223Gb, and it is VHD.
I will be glad for any suggestions, as i can not see where it went wrong, if you need other logfiles, please say so..
I have an snapshot in allocated state, which i can not delete, i do not know if this i related.
Please, help
Kind regards
Gert
catalina.out.txt 3.91K
1 downloads
management-server.log.txt 2.96K
2 downloads
Hello,
I just want some input.
We are a small company, trying out CloudPlatform, on 2 servers..
small setup for testing and trying 3 small customers in production.
We do have some problems with Cloudplatform, and in this perspective
i think we need to buy support, this is where i am thinking what?
vmware is 30% of the prices Citrix is asking.
is it worth paying so much extra, as i see it there are no support forums that work, so support is not optional, and vmware has been on the market +10 years...
What are your thoughts...
am i calculating this correct.. am i missing something, are there anything cloudstack deos better ?
any thoughts will be great..
Kind regards
Gert
All,
I have finally successfully configured Cloud Platform trial version and ready to create VM to build a test offering however when I try to create a VM as root or as a user I'm getting the following message: Unable to create a deployment for VM
Please see below some log messages from the Management Server. I need some help please
2013-12-27 18:26:30,534 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===START=== 192.168.168.250 -- GET command=listNetworks&trafficType=Guest&zoneId=00fac1a6-c3a5-4c53-a3ff-d9930e0c46bf&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&_=1388186780711
2013-12-27 18:26:30,559 DEBUG [cloud.api.ApiServlet] (catalina-exec-7:null) ===END=== 192.168.168.250 -- GET command=listNetworks&trafficType=Guest&zoneId=00fac1a6-c3a5-4c53-a3ff-d9930e0c46bf&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&_=1388186780711
2013-12-27 18:26:30,741 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===START=== 192.168.168.250 -- GET command=listSecurityGroups&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&domainid=11f9580c-b37e-4718-acd9-d065505cdcbb&account=cwilliams391&_=1388186780915
2013-12-27 18:26:30,760 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-24:null) Access granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] to Domain:3/Net-IT/ by AffinityGroupAccessChecker_EnhancerByCloudStack_6bdbded2
2013-12-27 18:26:30,762 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-24:null) Access to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:30,767 DEBUG [cloud.api.ApiServlet] (catalina-exec-24:null) ===END=== 192.168.168.250 -- GET command=listSecurityGroups&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&domainid=11f9580c-b37e-4718-acd9-d065505cdcbb&account=cwilliams391&_=1388186780915
2013-12-27 18:26:34,579 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-10:null) SeqA 3-2098: Processing Seq 3-2098: { Cmd , MgmtId: -1, via: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] }
2013-12-27 18:26:34,641 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-10:null) SeqA 3-2098: Sending Seq 3-2098: { Ans: , MgmtId: 132129486677709, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2013-12-27 18:26:38,841 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2013-12-27 18:26:39,240 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2013-12-27 18:26:39,580 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) SeqA 3-2099: Processing Seq 3-2099: { Cmd , MgmtId: -1, via: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] }
2013-12-27 18:26:39,702 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers to update status.
2013-12-27 18:26:39,704 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
2013-12-27 18:26:39,707 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-9:null) SeqA 3-2099: Sending Seq 3-2099: { Ans: , MgmtId: 132129486677709, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2013-12-27 18:26:39,733 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers to update status.
2013-12-27 18:26:39,734 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 networks to update RvR status.
2013-12-27 18:26:43,105 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===START=== 192.168.168.250 -- GET command=deployVirtualMachine&zoneId=00fac1a6-c3a5-4c53-a3ff-d9930e0c46bf&templateId=6915198c-6f19-11e3-96c8-8a8910b55ba7&hypervisor=XenServer&serviceOfferingId=127bbf46-af57-4de9-a08f-b6411e5a4697&diskOfferingId=557e5531-d5ff-400e-b699-d8a356899e1b&securitygroupids=909108f0-3735-4cb4-bed5-09414f20096c&displayname=carl&name=carl&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&_=1388186793283
2013-12-27 18:26:43,114 DEBUG [cloud.api.ApiDispatcher] (catalina-exec-11:null) InfrastructureEntity name is:com.cloud.offering.ServiceOffering
2013-12-27 18:26:43,118 DEBUG [cloud.api.ApiDispatcher] (catalina-exec-11:null) ControlledEntity name is:com.cloud.template.VirtualMachineTemplate
2013-12-27 18:26:43,121 DEBUG [cloud.api.ApiDispatcher] (catalina-exec-11:null) InfrastructureEntity name is:com.cloud.offering.DiskOffering
2013-12-27 18:26:43,123 DEBUG [cloud.api.ApiDispatcher] (catalina-exec-11:null) ControlledEntity name is:com.cloud.network.security.SecurityGroup
2013-12-27 18:26:43,125 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-11:null) Access to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,127 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-11:null) Access to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,128 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-11:null) Access to com.cloud.network.security.SecurityGroupVO$$EnhancerByCGLIB$$6edf5bbc@43e651d3 granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,131 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-11:null) Access to Tmpl[5-VHD-centos56-x86_64-xen granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,132 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-11:null) Access to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,138 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-11:null) Access to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,164 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-11:null) Access to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,164 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-11:null) Access to com.cloud.network.security.SecurityGroupVO$$EnhancerByCGLIB$$6edf5bbc@42dfab8e granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,306 DEBUG [cloud.vm.UserVmManagerImpl] (catalina-exec-11:null) Allocating in the DB for vm
2013-12-27 18:26:43,323 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-11:null) Allocating entries for VM: VM[User|carl]
2013-12-27 18:26:43,324 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-11:null) Allocating nics for VM[User|carl]
2013-12-27 18:26:43,324 DEBUG [cloud.network.NetworkManagerImpl] (catalina-exec-11:null) Allocating nic for vm VM[User|carl] in network Ntwk[204|Guest|6] with requested profile NicProfile[0-0-null-null-null
2013-12-27 18:26:43,333 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-11:null) Allocating disks for VM[User|carl]
2013-12-27 18:26:43,352 DEBUG [cloud.vm.VirtualMachineManagerImpl] (catalina-exec-11:null) Allocation completed for VM: VM[User|carl]
2013-12-27 18:26:43,352 DEBUG [cloud.vm.UserVmManagerImpl] (catalina-exec-11:null) Successfully allocated DB entry for VM[User|carl]
2013-12-27 18:26:43,724 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-11:null) submit async job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ], details: AsyncJobVO {id:35, userId: 7, accountId: 7, sessionKey: null, instanceType: VirtualMachine, instanceId: 16, cmd: org.apache.cloudstack.api.command.user.vm.DeployVMCmd, cmdOriginator: null, cmdInfo: {"sessionkey":"AelDWfiHsmTfwtNjtk5BRrkv5As\u003d","cmdEventType":"VM.CREATE","ctxUserId":"7","serviceOfferingId":"127bbf46-af57-4de9-a08f-b6411e5a4697","httpmethod":"GET","securitygroupids":"909108f0-3735-4cb4-bed5-09414f20096c","zoneId":"00fac1a6-c3a5-4c53-a3ff-d9930e0c46bf","id":"16","templateId":"6915198c-6f19-11e3-96c8-8a8910b55ba7","response":"json","hypervisor":"XenServer","name":"carl","diskOfferingId":"557e5531-d5ff-400e-b699-d8a356899e1b","_":"1388186793283","ctxAccountId":"7","ctxStartEventId":"164","displayname":"carl"}, cmdVersion: 0, callbackType: 0, callbackAddress: null, status: 0, processStatus: 0, resultCode: 0, result: null, initMsid: 132129486677709, completeMsid: null, lastUpdated: null, lastPolled: null, created: null}
2013-12-27 18:26:43,725 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Executing org.apache.cloudstack.api.command.user.vm.DeployVMCmd for job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]
2013-12-27 18:26:43,727 DEBUG [cloud.api.ApiServlet] (catalina-exec-11:null) ===END=== 192.168.168.250 -- GET command=deployVirtualMachine&zoneId=00fac1a6-c3a5-4c53-a3ff-d9930e0c46bf&templateId=6915198c-6f19-11e3-96c8-8a8910b55ba7&hypervisor=XenServer&serviceOfferingId=127bbf46-af57-4de9-a08f-b6411e5a4697&diskOfferingId=557e5531-d5ff-400e-b699-d8a356899e1b&securitygroupids=909108f0-3735-4cb4-bed5-09414f20096c&displayname=carl&name=carl&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&_=1388186793283
2013-12-27 18:26:43,730 DEBUG [cloud.api.ApiDispatcher] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) InfrastructureEntity name is:com.cloud.offering.ServiceOffering
2013-12-27 18:26:43,732 DEBUG [cloud.api.ApiDispatcher] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ControlledEntity name is:com.cloud.template.VirtualMachineTemplate
2013-12-27 18:26:43,734 DEBUG [cloud.api.ApiDispatcher] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) InfrastructureEntity name is:com.cloud.offering.DiskOffering
2013-12-27 18:26:43,736 DEBUG [cloud.api.ApiDispatcher] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ControlledEntity name is:com.cloud.network.security.SecurityGroup
2013-12-27 18:26:43,738 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Access to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,740 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Access to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,741 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Access to com.cloud.network.security.SecurityGroupVO$$EnhancerByCGLIB$$6edf5bbc@59f4de28 granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,743 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Access to Tmpl[5-VHD-centos56-x86_64-xen granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,805 DEBUG [cloud.user.AccountManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Access to VM[User|carl] granted to Acct[0dc1060b-a112-46d8-816f-d963b0435abc-cwilliams391] by DomainChecker_EnhancerByCloudStack_851647cd
2013-12-27 18:26:43,846 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Deploy avoids pods: [], clusters: [], hosts: [2]
2013-12-27 18:26:43,847 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_7c41c31e@5c1130f8
2013-12-27 18:26:43,847 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Trying to allocate a host and storage pools from dc:1, pod:null,cluster:null, requested cpu: 1000, requested ram: 1073741824
2013-12-27 18:26:43,847 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Is ROOT volume READY (pool already allocated)?: No
2013-12-27 18:26:43,847 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Searching all possible resources under this Zone: 1
2013-12-27 18:26:43,848 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Zone: 1
2013-12-27 18:26:43,850 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Removing from the clusterId list these clusters from avoid set: []
2013-12-27 18:26:43,855 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking resources in Cluster: 1 under Pod: 1
2013-12-27 18:26:43,856 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:1 cluster:1
2013-12-27 18:26:43,859 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) FirstFitAllocator has 2 hosts to check for allocation: [Host[-2-Routing], Host[-1-Routing]]
2013-12-27 18:26:43,861 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Found 2 hosts for allocation after prioritization: [Host[-2-Routing], Host[-1-Routing]]
2013-12-27 18:26:43,861 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Looking for speed=1000Mhz, Ram=1024
2013-12-27 18:26:43,861 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Host name: xenserver-PHL1, hostId: 2 is in avoid set, skipping this and trying other available hosts
2013-12-27 18:26:43,865 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Checking if host: 1 has enough capacity for requested CPU: 1000 and requested RAM: 1073741824 , cpuOverprovisioningFactor: 1.0
2013-12-27 18:26:43,866 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Hosts's actual total CPU: 44800 and CPU after applying overprovisioning: 44800
2013-12-27 18:26:43,866 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Free CPU: 44300 , Requested CPU: 1000
2013-12-27 18:26:43,866 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Free RAM: 130644566016 , Requested RAM: 1073741824
2013-12-27 18:26:43,866 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Host has enough CPU and RAM available
2013-12-27 18:26:43,866 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) STATS: Can alloc CPU from host: 1, used: 500, reserved: 0, actual total: 44800, total with overprovisioning: 44800; requested cpu:1000,alloc_from_last_host?:false ,considerReservedCapacity?: true
2013-12-27 18:26:43,866 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) STATS: Can alloc MEM from host: 1, used: 268435456, reserved: 0, total: 130913001472; requested mem: 1073741824,alloc_from_last_host?:false ,considerReservedCapacity?: true
2013-12-27 18:26:43,866 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Found a suitable host, adding to list: 1
2013-12-27 18:26:43,866 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] FirstFitRoutingAllocator) Host Allocator returning 1 suitable hosts
2013-12-27 18:26:43,868 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking suitable pools for volume (Id, Type): (26,ROOT)
2013-12-27 18:26:43,868 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) We need to allocate new storagepool for this volume
2013-12-27 18:26:43,868 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Calling StoragePoolAllocators to find suitable pools
2013-12-27 18:26:43,869 DEBUG [storage.allocator.LocalStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) LocalStoragePoolAllocator trying to find storage pool to fit the vm
2013-12-27 18:26:43,869 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ClusterScopeStoragePoolAllocator looking for storage pool
2013-12-27 18:26:43,869 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Looking for pools in dc: 1 pod:1 cluster:1
2013-12-27 18:26:43,872 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking if storage pool is suitable, name: null ,poolId: 3
2013-12-27 18:26:43,874 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool 3 for storage, totalSize: 48769794048, usedBytes: 6404702208, usedPct: 0.13132518463572743, disable threshold: 0.85
2013-12-27 18:26:43,883 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool: 3 for volume allocation [Vol[26|vm=16|ROOT]], maxSize : 97539588096, totalAllocatedSize : 4194304000, askingSize : 21474836480, allocated disable threshold: 0.85
2013-12-27 18:26:43,883 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ClusterScopeStoragePoolAllocator returning 1 suitable storage pools
2013-12-27 18:26:43,883 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking suitable pools for volume (Id, Type): (27,DATADISK)
2013-12-27 18:26:43,883 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) We need to allocate new storagepool for this volume
2013-12-27 18:26:43,884 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Calling StoragePoolAllocators to find suitable pools
2013-12-27 18:26:43,884 DEBUG [storage.allocator.LocalStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) LocalStoragePoolAllocator trying to find storage pool to fit the vm
2013-12-27 18:26:43,884 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ClusterScopeStoragePoolAllocator looking for storage pool
2013-12-27 18:26:43,884 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Looking for pools in dc: 1 pod:1 cluster:1
2013-12-27 18:26:43,886 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking if storage pool is suitable, name: null ,poolId: 3
2013-12-27 18:26:43,889 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool 3 for storage, totalSize: 48769794048, usedBytes: 6404702208, usedPct: 0.13132518463572743, disable threshold: 0.85
2013-12-27 18:26:43,894 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool: 3 for volume allocation [Vol[27|vm=16|DATADISK]], maxSize : 97539588096, totalAllocatedSize : 4194304000, askingSize : 21474836480, allocated disable threshold: 0.85
2013-12-27 18:26:43,895 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ClusterScopeStoragePoolAllocator returning 1 suitable storage pools
2013-12-27 18:26:43,895 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Trying to find a potenial host and associated storage pools from the suitable host/pool lists for this VM
2013-12-27 18:26:43,895 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking if host: 1 can access any suitable storage pool for volume: ROOT
2013-12-27 18:26:43,895 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Host: 1 can access pool: 3
2013-12-27 18:26:43,897 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool 3 for storage, totalSize: 48769794048, usedBytes: 6404702208, usedPct: 0.13132518463572743, disable threshold: 0.85
2013-12-27 18:26:43,905 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool: 3 for volume allocation [Vol[26|vm=16|ROOT]], maxSize : 97539588096, totalAllocatedSize : 4194304000, askingSize : 21474836480, allocated disable threshold: 0.85
2013-12-27 18:26:43,905 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking if host: 1 can access any suitable storage pool for volume: DATADISK
2013-12-27 18:26:43,906 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Host: 1 can access pool: 3
2013-12-27 18:26:43,907 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool 3 for storage, totalSize: 48769794048, usedBytes: 6404702208, usedPct: 0.13132518463572743, disable threshold: 0.85
2013-12-27 18:26:43,913 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool: 3 for volume allocation [Vol[27|vm=16|DATADISK]], maxSize : 97539588096, totalAllocatedSize : 4194304000, askingSize : 21474836480, allocated disable threshold: 0.85
2013-12-27 18:26:43,913 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Found a potential host id: 1 name: xenserver-PHL2 and associated storage pools for this VM
2013-12-27 18:26:43,914 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) 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(27|DATADISK-->Pool(3), Volume(26|ROOT-->Pool(3))]
2013-12-27 18:26:43,981 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) 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
2013-12-27 18:26:43,981 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Successfully transitioned to start state for VM[User|carl] reservation id = c156fe25-8658-4782-a3b9-4d9138b80414
2013-12-27 18:26:44,033 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Trying to deploy VM, vm has dcId: 1 and podId: null
2013-12-27 18:26:44,033 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) advanceStart: DeploymentPlan is provided, using dcId:1, podId: 1, clusterId: 1, hostId: 1, poolId: null
2013-12-27 18:26:44,033 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Deploy avoids pods: null, clusters: null, hosts: null
2013-12-27 18:26:44,042 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Deploy avoids pods: [], clusters: [], hosts: [2]
2013-12-27 18:26:44,042 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_7c41c31e@5c1130f8
2013-12-27 18:26:44,042 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested cpu: 1000, requested ram: 1073741824
2013-12-27 18:26:44,042 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Is ROOT volume READY (pool already allocated)?: No
2013-12-27 18:26:44,042 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) DeploymentPlan has host_id specified, choosing this host and making no checks on this host: 1
2013-12-27 18:26:44,043 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Looking for suitable pools for this host under zone: 1, pod: 1, cluster: 1
2013-12-27 18:26:44,044 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking suitable pools for volume (Id, Type): (26,ROOT)
2013-12-27 18:26:44,044 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) We need to allocate new storagepool for this volume
2013-12-27 18:26:44,045 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Calling StoragePoolAllocators to find suitable pools
2013-12-27 18:26:44,046 DEBUG [storage.allocator.LocalStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) LocalStoragePoolAllocator trying to find storage pool to fit the vm
2013-12-27 18:26:44,046 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ClusterScopeStoragePoolAllocator looking for storage pool
2013-12-27 18:26:44,046 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Looking for pools in dc: 1 pod:1 cluster:1
2013-12-27 18:26:44,049 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking if storage pool is suitable, name: null ,poolId: 3
2013-12-27 18:26:44,051 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool 3 for storage, totalSize: 48769794048, usedBytes: 6404702208, usedPct: 0.13132518463572743, disable threshold: 0.85
2013-12-27 18:26:44,060 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool: 3 for volume allocation [Vol[26|vm=16|ROOT]], maxSize : 97539588096, totalAllocatedSize : 4194304000, askingSize : 21474836480, allocated disable threshold: 0.85
2013-12-27 18:26:44,060 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ClusterScopeStoragePoolAllocator returning 1 suitable storage pools
2013-12-27 18:26:44,060 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking suitable pools for volume (Id, Type): (27,DATADISK)
2013-12-27 18:26:44,060 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) We need to allocate new storagepool for this volume
2013-12-27 18:26:44,060 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Calling StoragePoolAllocators to find suitable pools
2013-12-27 18:26:44,060 DEBUG [storage.allocator.LocalStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) LocalStoragePoolAllocator trying to find storage pool to fit the vm
2013-12-27 18:26:44,060 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ClusterScopeStoragePoolAllocator looking for storage pool
2013-12-27 18:26:44,060 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Looking for pools in dc: 1 pod:1 cluster:1
2013-12-27 18:26:44,063 DEBUG [storage.allocator.AbstractStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking if storage pool is suitable, name: null ,poolId: 3
2013-12-27 18:26:44,065 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool 3 for storage, totalSize: 48769794048, usedBytes: 6404702208, usedPct: 0.13132518463572743, disable threshold: 0.85
2013-12-27 18:26:44,071 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool: 3 for volume allocation [Vol[27|vm=16|DATADISK]], maxSize : 97539588096, totalAllocatedSize : 4194304000, askingSize : 21474836480, allocated disable threshold: 0.85
2013-12-27 18:26:44,071 DEBUG [storage.allocator.ClusterScopeStoragePoolAllocator] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) ClusterScopeStoragePoolAllocator returning 1 suitable storage pools
2013-12-27 18:26:44,071 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Trying to find a potenial host and associated storage pools from the suitable host/pool lists for this VM
2013-12-27 18:26:44,071 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking if host: 1 can access any suitable storage pool for volume: ROOT
2013-12-27 18:26:44,072 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Host: 1 can access pool: 3
2013-12-27 18:26:44,073 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool 3 for storage, totalSize: 48769794048, usedBytes: 6404702208, usedPct: 0.13132518463572743, disable threshold: 0.85
2013-12-27 18:26:44,082 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool: 3 for volume allocation [Vol[26|vm=16|ROOT]], maxSize : 97539588096, totalAllocatedSize : 4194304000, askingSize : 21474836480, allocated disable threshold: 0.85
2013-12-27 18:26:44,082 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking if host: 1 can access any suitable storage pool for volume: DATADISK
2013-12-27 18:26:44,082 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Host: 1 can access pool: 3
2013-12-27 18:26:44,083 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool 3 for storage, totalSize: 48769794048, usedBytes: 6404702208, usedPct: 0.13132518463572743, disable threshold: 0.85
2013-12-27 18:26:44,089 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Checking pool: 3 for volume allocation [Vol[27|vm=16|DATADISK]], maxSize : 97539588096, totalAllocatedSize : 4194304000, askingSize : 21474836480, allocated disable threshold: 0.85
2013-12-27 18:26:44,090 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Found a potential host id: 1 name: xenserver-PHL2 and associated storage pools for this VM
2013-12-27 18:26:44,090 DEBUG [cloud.server.StatsCollector] (StatsCollector-1:null) StorageCollector is running...
2013-12-27 18:26:44,090 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) 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(27|DATADISK-->Pool(3), Volume(26|ROOT-->Pool(3))]
2013-12-27 18:26:44,090 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Deployment found - P0=VM[User|carl], 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(27|DATADISK-->Pool(3), Volume(26|ROOT-->Pool(3))]
2013-12-27 18:26:44,154 DEBUG [agent.transport.Request] (StatsCollector-1:null) Seq 4-1417543939: Received: { Ans: , MgmtId: 132129486677709, via: 4, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-12-27 18:26:44,160 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-182:null) Seq 1-327090720: Executing request
2013-12-27 18:26:44,165 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) 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
2013-12-27 18:26:44,174 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Hosts's actual total CPU: 44800 and CPU after applying overprovisioning: 44800
2013-12-27 18:26:44,174 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) We are allocating VM, increasing the used capacity of this host:1
2013-12-27 18:26:44,174 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Current Used CPU: 500 , Free CPU:44300 ,Requested CPU: 1000
2013-12-27 18:26:44,174 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Current Used RAM: 268435456 , Free RAM:130644566016 ,Requested RAM: 1073741824
2013-12-27 18:26:44,174 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) CPU STATS after allocation: for host: 1, old used: 500, old reserved: 0, actual total: 44800, total with overprovisioning: 44800; new used:1500, reserved:0; requested cpu:1000,alloc_from_last:false
2013-12-27 18:26:44,174 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) RAM STATS after allocation: for host: 1, old used: 268435456, old reserved: 0, total: 130913001472; new used: 1342177280, reserved: 0; requested mem: 1073741824,alloc_from_last:false
2013-12-27 18:26:44,266 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) VM is being created in podId: 1
2013-12-27 18:26:44,274 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Network id=204 is already implemented
2013-12-27 18:26:44,344 DEBUG [db.Transaction.Transaction] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Rolling back the transaction: Time = 7 Name = -AsyncJobManagerImpl$1.run:494-Executors$RunnableAdapter.call:471-FutureTask.run:262-ThreadPoolExecutor.runWorker:1145-ThreadPoolExecutor$Worker.run:615-Thread.run:744; called by -Transaction.rollback:897-Transaction.removeUpTo:840-Transaction.close:664-TransactionContextBuilder.interceptException:63-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:133-NetworkManagerImpl.assignPublicIpAddress:376-DirectPodBasedNetworkGuru.getIp:186-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-DirectPodBasedNetworkGuru.reserve:153-ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept:125-NetworkManagerImpl.prepareNic:2166-NetworkManagerImpl.prepare:2136
2013-12-27 18:26:44,344 INFO [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Insufficient capacity
com.cloud.exception.InsufficientAddressCapacityException: Insufficient address capacityScope=interface com.cloud.dc.Pod; id=1
at com.cloud.network.NetworkManagerImpl.fetchNewPublicIp(NetworkManagerImpl.java:472)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at com.cloud.network.NetworkManagerImpl.assignPublicIpAddress(NetworkManagerImpl.java:376)
at com.cloud.network.guru.DirectPodBasedNetworkGuru.getIp(DirectPodBasedNetworkGuru.java:186)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at com.cloud.network.guru.DirectPodBasedNetworkGuru.reserve(DirectPodBasedNetworkGuru.java:153)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at com.cloud.network.NetworkManagerImpl.prepareNic(NetworkManagerImpl.java:2166)
at com.cloud.network.NetworkManagerImpl.prepare(NetworkManagerImpl.java:2136)
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:886)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:227)
at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3442)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3002)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2988)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:420)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
2013-12-27 18:26:44,401 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Cleaning up resources for the vm VM[User|carl] in Starting state
2013-12-27 18:26:44,403 DEBUG [agent.transport.Request] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Seq 1-327090721: Sending { Cmd , MgmtId: 132129486677709, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"i-7-16-VM","wait":0}}] }
2013-12-27 18:26:44,403 DEBUG [agent.transport.Request] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Seq 1-327090721: Executing: { Cmd , MgmtId: 132129486677709, via: 1, Ver: v1, Flags: 100111, [{"com.cloud.agent.api.StopCommand":{"isProxy":false,"executeInSequence":true,"vmName":"i-7-16-VM","wait":0}}] }
2013-12-27 18:26:44,403 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-185:null) Seq 1-327090721: Executing request
2013-12-27 18:26:44,422 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-182:null) Seq 1-327090720: Response Received:
2013-12-27 18:26:44,422 DEBUG [agent.transport.Request] (StatsCollector-1:null) Seq 1-327090720: Received: { Ans: , MgmtId: 132129486677709, via: 1, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2013-12-27 18:26:44,484 INFO [xen.resource.CitrixResourceBase] (DirectAgent-185:null) VM does not exist on XenServerabf38ca8-e00f-40e6-89da-0e4794ee75b9
2013-12-27 18:26:44,484 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-185:null) Seq 1-327090721: Response Received:
2013-12-27 18:26:44,484 DEBUG [agent.transport.Request] (DirectAgent-185:null) Seq 1-327090721: Processing: { Ans: , MgmtId: 132129486677709, via: 1, Ver: v1, Flags: 110, [{"com.cloud.agent.api.StopAnswer":{"result":true,"details":"VM does not exist","wait":0}}] }
2013-12-27 18:26:44,484 DEBUG [agent.manager.AgentAttache] (DirectAgent-185:null) Seq 1-327090721: No more commands found
2013-12-27 18:26:44,484 DEBUG [agent.transport.Request] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Seq 1-327090721: Received: { Ans: , MgmtId: 132129486677709, via: 1, Ver: v1, Flags: 110, { StopAnswer } }
2013-12-27 18:26:44,548 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Asking VirtualRouter to release Nic[21-16-c156fe25-8658-4782-a3b9-4d9138b80414-null]
2013-12-27 18:26:44,551 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Asking SecurityGroupProvider to release Nic[21-16-c156fe25-8658-4782-a3b9-4d9138b80414-null]
2013-12-27 18:26:44,551 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Successfully released network resources for the vm VM[User|carl]
2013-12-27 18:26:44,551 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Successfully cleanued up resources for the vm VM[User|carl] in Starting state
2013-12-27 18:26:44,558 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Deploy avoids pods: [1], clusters: [], hosts: [1, 2]
2013-12-27 18:26:44,558 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_7c41c31e@5c1130f8
2013-12-27 18:26:44,558 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested cpu: 1000, requested ram: 1073741824
2013-12-27 18:26:44,558 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Is ROOT volume READY (pool already allocated)?: No
2013-12-27 18:26:44,558 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) DeploymentPlan has host_id specified, choosing this host and making no checks on this host: 1
2013-12-27 18:26:44,560 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) The specified host is in avoid set
2013-12-27 18:26:44,560 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Cannnot deploy to specified host, returning.
2013-12-27 18:26:44,658 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) 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
2013-12-27 18:26:44,666 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Hosts's actual total CPU: 44800 and CPU after applying overprovisioning: 44800
2013-12-27 18:26:44,666 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Hosts's actual total RAM: 130913004096 and RAM after applying overprovisioning: 130913001472
2013-12-27 18:26:44,666 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) release cpu from host: 1, old used: 1500,reserved: 0, actual total: 44800, total with overprovisioning: 44800; new used: 500,reserved:0; movedfromreserved: false,moveToReserveredfalse
2013-12-27 18:26:44,666 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) release mem from host: 1, old used: 1342177280,reserved: 0, total: 130913001472; new used: 268435456,reserved:0; movedfromreserved: false,moveToReserveredfalse
2013-12-27 18:26:44,891 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) 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
2013-12-27 18:26:44,891 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Successfully transitioned to start state for VM[User|carl] reservation id = a8378d70-f35e-47f2-94e8-fcbf1c963565
2013-12-27 18:26:44,941 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Trying to deploy VM, vm has dcId: 1 and podId: 1
2013-12-27 18:26:44,941 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Deploy avoids pods: [1], clusters: [], hosts: [1, 2]
2013-12-27 18:26:44,949 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Deploy avoids pods: [1], clusters: [], hosts: [1, 2]
2013-12-27 18:26:44,950 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) DeploymentPlanner allocation algorithm: com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_7c41c31e@5c1130f8
2013-12-27 18:26:44,950 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:null, requested cpu: 1000, requested ram: 1073741824
2013-12-27 18:26:44,950 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Is ROOT volume READY (pool already allocated)?: No
2013-12-27 18:26:44,950 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Searching resources only under specified Pod: 1
2013-12-27 18:26:44,950 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) The specified pod is in avoid set, returning.
2013-12-27 18:26:45,035 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) VM state transitted from :Starting to Stopped with event: OperationFailedvm's original host id: null new host id: null host id before state transition: null
2013-12-27 18:26:45,081 DEBUG [cloud.vm.UserVmManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Destroying vm VM[User|carl] as it failed to create on Host with Id:null
2013-12-27 18:26:45,235 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) VM state transitted from :Stopped to Error with event: OperationFailedToErrorvm's original host id: null new host id: null host id before state transition: null
2013-12-27 18:26:45,442 WARN [apache.cloudstack.alerts] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) alertType:: 8 // dataCenterId:: 1 // podId:: 1 // clusterId:: null // message:: Failed to deploy Vm with Id: 16, on Host with Id: null
2013-12-27 18:26:45,726 INFO [user.vm.DeployVMCmd] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|carl]Scope=interface com.cloud.dc.DataCenter; id=1
2013-12-27 18:26:45,727 INFO [user.vm.DeployVMCmd] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Unable to create a deployment for VM[User|carl]
com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|carl]Scope=interface com.cloud.dc.DataCenter; id=1
at com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:841)
at com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:577)
at org.apache.cloudstack.engine.cloud.entity.api.VMEntityManagerImpl.deployVirtualMachine(VMEntityManagerImpl.java:237)
at org.apache.cloudstack.engine.cloud.entity.api.VirtualMachineEntityImpl.deploy(VirtualMachineEntityImpl.java:209)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3442)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3002)
at com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2988)
at com.cloud.utils.component.ComponentInstantiationPostProcessor$InterceptorDispatcher.intercept(ComponentInstantiationPostProcessor.java:125)
at org.apache.cloudstack.api.command.user.vm.DeployVMCmd.execute(DeployVMCmd.java:420)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:158)
at com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:531)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
2013-12-27 18:26:45,729 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-35:job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ]) Complete async job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ], jobStatus: 2, resultCode: 530, result: Error Code: 533 Error text: Unable to create a deployment for VM[User|carl]
2013-12-27 18:26:46,532 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-12:null) Ping from 4
2013-12-27 18:26:46,915 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===START=== 192.168.168.250 -- GET command=queryAsyncJobResult&jobId=e1ebce9d-18d7-4cf0-98de-22399a6ec49e&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&_=1388186797093
2013-12-27 18:26:46,928 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-22:null) Async job-35 = [ e1ebce9d-18d7-4cf0-98de-22399a6ec49e ] completed
2013-12-27 18:26:46,932 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===END=== 192.168.168.250 -- GET command=queryAsyncJobResult&jobId=e1ebce9d-18d7-4cf0-98de-22399a6ec49e&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&_=1388186797093
2013-12-27 18:26:47,047 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===START=== 192.168.168.250 -- GET command=listVirtualMachines&id=0ad3abc4-b171-4fe3-81e2-f069a365a900&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&_=1388186797186
2013-12-27 18:26:47,076 DEBUG [cloud.api.ApiServlet] (catalina-exec-5:null) ===END=== 192.168.168.250 -- GET command=listVirtualMachines&id=0ad3abc4-b171-4fe3-81e2-f069a365a900&response=json&sessionkey=AelDWfiHsmTfwtNjtk5BRrkv5As%3D&_=1388186797186
2013-12-27 18:26:49,580 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-13:null) SeqA 3-2100: Processing Seq 3-2100: { Cmd , MgmtId: -1, via: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": []\n}","wait":0}}] }
2013-12-27 18:26:49,617 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-13:null) SeqA 3-2100: Sending Seq 3-2100: { Ans: , MgmtId: 132129486677709, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
Hi all,
I'm curently in the processs of implementing cloudplateform trial version.
Eveything went fine until now :
installation ok, first instance creation from template (centos) ok, etc.
I am now trying to upload a iso. And my problem started at that point.
I had different erros while trying to upload my iso.
I then connected to the ssvm to start the check script that fails on dns resolution.
For my test, I defined 8.8.8.8 as dns servers.
I can ping 8.8.8.8 or any other ip, but no DNS resolution can be performed.
If I try nslookup or dig, I always have a connection time out.
Do you have any idea ?
Regards.
Greg
Hi,
Has anyone come up with a way to perform bulk VM migrations into CCP? Currently we are using DT Move however this is an expensive method.
Is there anything on the roadmap for bulk import, particularly when using vSphere?
Cheers,
Simon
Hi,
Is it possible to offload firewall, SourceNAT and inter-VLAN routing functions to hardware devices for VPC networks or is this supported only for Isolated Networks?
Thanks,
Simon
Hi,
I would like to know if anyone has managed to configure a service offering for a guest network that has no connectivity to the outside world(ie a guest net with no VR).
I would like customer to be able to provision a totally isolated network that has no internet connectivity or connecvivity to any other networks so that they can use their own softwre firewall to provide the inter-VLAN routing and security services.
I can set up a shared netwrok with no services, however I cant have overlapping IP ranges with this network type. I can also configure the guest network with no services, however when I go to deploy a network from the offering it is not avaiable for selection.
Really hoping someone has this working as it is a pretty core requiement for a customer.
Thanks,
Simon
Hey guys,
Really hoping someone can help me out here.
I have installed CloudPlatform 4.2 and am trying to add iscsi storage as primary storage.
It keeps failing and just says unable to add storage.
Checking the logs on the management server, I can see that its failing authentication to connect to our Dell EqualLogic.
It doesnt ask me for the chaps username or password anywhere in the add storage screen, so how do I add credentials to this connection so that it wont fail?
Many thanks in advance!
Jonny
All,
I'm new to the Citrix and Linux environment but never the less I'm in the process of trying the CloudPlatform trial installation. The problem I'm been stuck with now is the mounting of the shared secondary server just before the download of the VM templates.
I'm using a box running OS Redhat 6.5 Enterprises. I'm following the full installation guide as my Management Server is on it's on machine on it's own node. My NFS server is running Centos on VM with Xenserver 6.x as the OS. From the management server I'm trying to mount : mount -t nfs nfs:/nfs/export/secondary /mnt/secondary but now I'm receiving a message: mount.nfs: Connection timed out
before I was getting the message permission denied from server but after a reboot of the NFS and management server I'm now at the connection timed out- while trying to mnt the drive.
Any help is appreciated
Is it possible to create a network offering, which would use a custom virtual appliance instead of the default Debian template for the virtual router?
I'd like to provide a premium network offering, with a commercial security gateway/UTM virtual appliance as network provider. Ie the FortiGate UTM provides VPN, NAT, DNS, DHCP, routing and other network features similar to Virtual Router, but also offers security features like anitispam, virus scanning, deep packet inspection, IPS etc.
I can create a network like this
Internet -> ACS VR-> Fortigate TM VM -> other VM
But not sure how can force all the traffic from the VMs to go via the FortiGate. So having the UTM as a custom network provider would ensure it?
The environment is XenServer 6.2 and CP 4.2.1 and Advanced Networking
Thanks,
Michal
I've changed the global setting of :
kvm.snapshot.enabled true
Then restarted the management server. ( also restarted cloud-agent on all computes, just to make sure. )
And yet, I'm not able to create snapshot from running VM.
Log shows -
2013-11-27 10:59:09,346 INFO [cloud.api.ApiServer] (http-6443-exec-5:null) VM snapshot is not enabled for hypervisor type: KVM
google shows this
Regards,
Yossi
Hello,
I have read the instructions on how to create a VPC private gateway, but i can not figure what to put
in the different boxes.. 
I have a customer they are using VLAN12 (on the cloud), with an IP range 10.17.20.0/24, and now i have put in a 1gb direct connection to their office i have created a vlan40 an plugged it in, the iP address on this router is: 10.17.20.4/24.
I know Physical network, vlan but other than this, i can not figure it out.
Kind regards
Gert
Hello,
I have installed some new NICS in our hosts.
Now, i want to see them inside cloudstack, i know i can not add them using th UI, but do
anybody know the curl command i need to know.
I you have one og two examples that would be great... :)
Kind regards
Gert
Has anyone had to upgrade from 3.0.6 where they had multiple IPs assigned per VM (i.e.: CloudPlatform assigned the initial IP and then you manually assigned additional IPs within the VM?) Did you leave the additional IPs assigned to the VMs during the uprade, and were they still reachable after the upgrade was completed? Essentially I'm in a situation where it would be very difficult for me to un-assign IPs which were manually added to the VM and I'm trying to see what kind of impact there might be if I leave those IPs as is and proceed with the upgrade.
I am trying to upgrade a 3.0.6 Patch F install to 4.2 but it keeps failing with the following
Updating the CloudPlatform and its dependencies...
Loaded plugins: fastestmirror
Loading mirror speeds from cached hostfile
* base: mirrors.coreix.net
* epel: mirrors.coreix.net
* extras: mirrors.vooservers.com
* updates: mirrors.coreix.net
base | 3.7 kB 00:00
cloud-temp | 1.3 kB 00:00 ...
epel | 3.6 kB 00:00
extras | 3.4 kB 00:00
updates | 3.4 kB 00:00
Setting up Update Process
Package(s) cloudstack-* available, but not installed.
No Packages marked for Update
Done
Is there any way to correct that?
Running 4.2.0 on CentOS 6.4
###Sample
Sent: Friday, January 16, 1970 9:15 AM
To: Gordon
Subject: You are invited to join the cloud stack project id=3
You've been invited to join the CloudStack project id=3. Please use token 4U4OL19T6O to complete registration
###
Thanks,
Gordy
Edited by: gstrona360 on Nov 5, 2013 10:38 AM
Edited by: gstrona360 on Nov 5, 2013 10:38 AM
I try to add a VHD file as a template. But it didn't work as expected. After I add the URL to the VHD file the entry disapears and no usable template was created. I know that the VHD file is 18 GB in size and need some time to copy. On the XenCenter I see high network and disk usage, but the template isn't available after 24 hours.
Any hints for me?
Thomas
Hi,
I'm unable to delete a zone cause there are vmware datacenters associated with it but I can't find where to remove them, in fact, I can't see where is the association since i remove everything from the zone already. Is there a way to remove the datacenter from the zone using the UI? or do I have to use some other procedure?
Thanks a lot for your response,
Hello, Is it possible to transition from a 4.2 ACS install to a CCP installation at the same version? Is there a documented procedure if so? Thanks!
tim
Hello,
anybody know if there is a new CloudInstanceManager.msi and where i can download this.
The url in the manual does not work... :)
Thank you
Kind regards
Gert
Hi all,
Now, I can not run ocfs2 on CentOS 6.3. I installed successfully .rpm packages (ocfs2-tools-1.6.3-2.el6.x86_64.rpm, ocfs2-kmod-1.5.0-1.el6.x86_64.rpm), which is contained in CloudStack-PATCH_F-3.0.6-8-rhel6.3.tar.gz packages. But when I start o2cb service it have problems:
[root@centos-6-3]# /etc/init.d/o2cb start
Loading filesystem "configfs": OK
Mounting configfs filesystem at /sys/kernel/config: OK
Loading filesystem "ocfs2_dlmfs": Unable to load filesystem "ocfs2_dlmfs"
Failed
Please help me
thanks.
Hi all,
Please let me know how to can download CloudPlatform-4.2 trial verion? I have tried download from citrix website with my account but not success. Please help me. Thanks.
Runnnig an 8 core with 32 gig ram, WHM/cPanel bare metal server.
When setting up the cloudstack "host", i accidently used the main ip domain name of the server which moved all my ip addresses from eth0 to cloudbr0 instead of leaving them on eth0 where they belong.
SOLVED, CLOSE/DELETE
Edited by: citrix-apex on Oct 15, 2013 1:19 PM
Hello,
After the upgrade i am having trouble creating instances.
I am getting.
Failed to increment resource count of type primary_storage for account id.
when i tail -f management-server.log i get
2013-10-13 11:08:42,084 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-56:null) Vm cpu utilization 7.735
2013-10-13 11:08:42,103 WARN [xen.resource.CitrixResourceBase] (DirectAgent-56:null) Error while collecting disk stats from :
You gave an invalid object reference. The object may have recently been deleted. The class parameter gives the type of reference given, and the handle parameter echoes the bad value given.
at com.xensource.xenapi.Types.checkResponse(Types.java:209)
at com.xensource.xenapi.Connection.dispatch(Connection.java:368)
at com.cloud.hypervisor.xen.resource.XenServerConnectionPool$XenServerConnection.dispatch(XenServerConnectionPool.java:909)
at com.xensource.xenapi.VBDMetrics.getIoReadKbs(VBDMetrics.java:210)
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.getVmStats(CitrixResourceBase.java:2731)
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:2631)
at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:493)
at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:59)
at com.cloud.hypervisor.xen.resource.XenServer610Resource.executeRequest(XenServer610Resource.java:106)
at com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:186)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$101(ScheduledThreadPoolExecutor.java:165)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:679)
2013-10-13 11:08:42,106 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-56:null) Seq 2-1599930409: Response Received:
?
I think i says that there is missing a disk of some sort.. ?
I am not too sure about my secondary storage, how can i check that cloudstack can see this..
anybody ?
Kind regards
Gert
Edited by: Gert Jensen on 13-10-2013 11:10
Hello..
I have Upgraded from CloudPlatform 3.0.7 to 4.2, the system is running,
and i can not see any errors..
BUT
All routers and system vms are missing xstools, is this by design ?
as i can not move them from one server to another with our rebooting them ...
Have i done somthing wrong.. ?
I did upload the template before the upgrade, as stated in the manual, i only have xenserver.
I have run the last command:
nohup cloudstack-sysvmadm -d 192.168.1.5 -u cloud -p password -a > sysvm.log 2>&1 &
changeing the ip and password so it fits my install.
and i have no idea of hos the check if the routers are upgraded using this command:
# ssh -i /var/cloudstack/management/.ssh/id_rsa <private-ip> -p 3922
# cat /etc/cloudstack-release
as i can not see privete-ip.. :)
I am using xenserver 6.2 with an upgraded install, not fresh.
Kind regards
Gert
Edited by: Gert Jensen on 12-10-2013 12:09
Hi All,
I have installed Citrix Cloudplatform - 4.2 Trial version on CentOS 6.3.
The installation went successful but i cannot able to login in UI,
Username: admin
Password: password
When i click the "Login" button, it shows this error,
Invalid username or password
After some workaround also i cannot able to fix this issue, even i tried reinstalling the OS(CentOS 6.3) as well CCP-4.2. But it didn't work, same error.
Log Entries:
/var/log/cloudstack/management/management-server.log
root@server CloudPlatform-4.2.0-2-rhel6.4# tail -f /var/log/cloudstack/management/management-server.log
2013-10-05 08:59:44,546 DEBUG [server.auth.MD5UserAuthenticator] (catalina-exec-17:null) Retrieving user: admin
2013-10-05 08:59:44,560 DEBUG [server.auth.MD5UserAuthenticator] (catalina-exec-17:null) Password does not match
2013-10-05 08:59:44,568 DEBUG [server.auth.LDAPUserAuthenticator] (catalina-exec-17:null) Retrieving user: admin
2013-10-05 08:59:44,597 DEBUG [server.auth.LDAPUserAuthenticator] (catalina-exec-17:null) LDAP authenticator is not configured.
2013-10-05 08:59:44,597 DEBUG [server.auth.PlainTextUserAuthenticator] (catalina-exec-17:null) Retrieving user: admin
2013-10-05 08:59:44,604 DEBUG [server.auth.PlainTextUserAuthenticator] (catalina-exec-17:null) Password does not match
2013-10-05 08:59:44,605 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-17:null) Unable to authenticate user with username admin in domain 1
2013-10-05 08:59:44,620 INFO [cloud.user.AccountManagerImpl] (catalina-exec-17:null) User admin is disabled/locked
2013-10-05 08:59:44,626 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-17:null) User: admin in domain 1 has failed to log in
2013-10-05 08:59:44,643 DEBUG [cloud.api.ApiServlet] (catalina-exec-17:null) ===END=== 64.103.232.147 -- POST
2013-10-05 09:00:28,313 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===START=== 64.103.232.147 -- POST
2013-10-05 09:00:28,321 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-21:null) Attempting to log in user: admin in domain 1
2013-10-05 09:00:28,322 DEBUG [server.auth.SHA256SaltedUserAuthenticator] (catalina-exec-21:null) Retrieving user: admin
2013-10-05 09:00:28,329 WARN [server.auth.SHA256SaltedUserAuthenticator] (catalina-exec-21:null) The stored password for admin isn't in the right format for this authenticator
2013-10-05 09:00:28,335 DEBUG [server.auth.MD5UserAuthenticator] (catalina-exec-21:null) Retrieving user: admin
2013-10-05 09:00:28,339 DEBUG [server.auth.MD5UserAuthenticator] (catalina-exec-21:null) Password does not match
2013-10-05 09:00:28,340 DEBUG [server.auth.LDAPUserAuthenticator] (catalina-exec-21:null) Retrieving user: admin
2013-10-05 09:00:28,349 DEBUG [server.auth.LDAPUserAuthenticator] (catalina-exec-21:null) LDAP authenticator is not configured.
2013-10-05 09:00:28,349 DEBUG [server.auth.PlainTextUserAuthenticator] (catalina-exec-21:null) Retrieving user: admin
2013-10-05 09:00:28,353 DEBUG [server.auth.PlainTextUserAuthenticator] (catalina-exec-21:null) Password does not match
2013-10-05 09:00:28,354 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-21:null) Unable to authenticate user with username admin in domain 1
2013-10-05 09:00:28,362 INFO [cloud.user.AccountManagerImpl] (catalina-exec-21:null) User admin is disabled/locked
2013-10-05 09:00:28,363 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-21:null) User: admin in domain 1 has failed to log in
2013-10-05 09:00:28,369 DEBUG [cloud.api.ApiServlet] (catalina-exec-21:null) ===END=== 64.103.232.147 -- POST
Can anyone help me to fix this issue?
Regards,
Karthikeyan
Hi
In 4.2 there is an option to specify that compute service offering that is created can be associated to a specific domain. This can be done by removing the public checkbox and then select the domain that the service offering should be associated with. It seems that this setting does not have impact because all compute service offerings are visible in all domains when creating an instance?
Ask, Discuss, Answer
