CloudPlatform 4.x

 
 
Neil Soyez
Site-2-Site VPN with SonicWALL

 I know that on Cisco and Juniper firewalls are supported for setting up S2S VPNs between a customer site and a VPC.   But, we have clients that have been using SonicWALL to connect to their VPCs for a couple of years now.   We upgraded from 4.5.1 to 4.7.1 and now none of those connections are working.  Has anyone been able to successfully get a VPC to SonicWALL s2s VPN to work?   If so, am I missing something?

26 0
Administrator
Unable to create a deployment for VM
Avatar

Unable to create a deployment for VM

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}}] }
 

 


Carl Williams MEMBERS 27 December 2013 - 23:28 PM
17 0
Administrator
Management Server shut down alone !!
Avatar

Management Server shut down alone !!

hi everybody,

 

I installed a virtual machine centos with this tutorial http://docs.cloudstack.apache.org/projects/cloudstack-installation/en/latest/qig.html

 

I have centos 6.5, xenserver 6.2

 

Installation finished with success.

I have a web access http://IP:8080/client

I'm ready for basic configuration. But 3 or 5 minut the VM shut down alone clearly.

 

What's wrong with Virtual Machine. I must look where (log) ?

 

Can you help me ?

 

Thanks for your help.

 

Clément.


Clément Mutz MEMBERS 08 April 2014 - 08:13 AM
16 0
Administrator
console proxy or system vm failed to start
Avatar

console proxy or system vm failed to start

Hi community !

 

I used xenserver 6.2 and cloudstack 4.3.

 

On vm Ubuntu 11.04 , installation finished with success.

 

I followed this guide http://docs.cloudstack.apache.org/projects/cloudstack-installation/en/latest/installation.html

 

I mounted partition nfs on VM after :

/usr/share/cloudstack-common/scripts/storage/secondary/cloud-install-sys-tmplt -m /tmp/secondary -u http://download.cloud.com/templates/4.3/systemvm64template-2014-01-14-master-xen.vhd.bz2 -h xenserver

 

 

umounted and deleted the provisional directory /tmp/secondary.

 

When i try to launch vm console proxy or system : attach file

 

What's wrong with my configuration ?

 

I forget something ?

 

Can you help me ?

 

Thanks for your help.

 

Clément.

 


Clément Mutz MEMBERS 02 June 2014 - 15:09 PM
14 0
Administrator
Unable to add xenserver host to cloudstack
Avatar

Unable to add xenserver host to cloudstack

Hello,

 

I installed cloudstack management 4.5.2 on centos 6.5 and I installed Xenserver 6.5 Somehow I'm unable to add de xenserver as host. The Cloudstack server is also running as NFS for sharing the primary and secondary shares. The cloudstack server  can ping the xenserver (hostname and IP-adress).

 

I have to say, I'm new to cloudstack and xenserver and the guides I used are:

 

The log file at(/var/log/cloudstack/management/management-server.log showed the followwing error:

 

2015-10-06 10:04:50,592 DEBUG [c.c.h.Status] (catalina-exec-13:ctx-02b60111 ctx-58b6cd50) Transition:[Resource state = Enabled, Agent event = AgentDisconnected, Host id = 5, name = xenserver]
2015-10-06 10:04:50,592 DEBUG [c.c.h.Status] (catalina-exec-13:ctx-02b60111 ctx-58b6cd50) Cannot transit agent status with event AgentDisconnected for host 5, name=xenserver, mangement server id is 91768838150
2015-10-06 10:04:50,592 ERROR [c.c.a.ApiServer] (catalina-exec-13:ctx-02b60111 ctx-58b6cd50) unhandled exception executing api command: [Ljava.lang.String;@3c18f4ab
com.cloud.utils.exception.CloudRuntimeException: Cannot transit agent status with event AgentDisconnected for host 5, mangement server id is 91768838150,Unable to transition to a new state from Creating via AgentDiscon$
        at com.cloud.agent.manager.AgentManagerImpl.agentStatusTransitTo(AgentManagerImpl.java:1363)
        at com.cloud.resource.ResourceManagerImpl.markHostAsDisconnected(ResourceManagerImpl.java:1749)
        at com.cloud.resource.ResourceManagerImpl.createHostAndAgent(ResourceManagerImpl.java:1810)
        at com.cloud.resource.ResourceManagerImpl.discoverHostsFull(ResourceManagerImpl.java:780)
        at com.cloud.resource.ResourceManagerImpl.discoverHosts(ResourceManagerImpl.java:593)
        at sun.reflect.GeneratedMethodAccessor194.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:606)
        at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
        at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
        at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
        at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
        at com.sun.proxy.$Proxy149.discoverHosts(Unknown Source)
        at org.apache.cloudstack.api.command.admin.host.AddHostCmd.execute(AddHostCmd.java:142)
        at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:141)
        at com.cloud.api.ApiServer.queueCommand(ApiServer.java:704)
        at com.cloud.api.ApiServer.handleRequest(ApiServer.java:529)
        at com.cloud.api.ApiServlet.processRequestInContext(ApiServlet.java:288)
        at com.cloud.api.ApiServlet$1.run(ApiServlet.java:119)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
        at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
        at com.cloud.api.ApiServlet.processRequest(ApiServlet.java:116)
        at com.cloud.api.ApiServlet.doPost(ApiServlet.java:83)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:637)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)

 

I also get the folowwing error in the cloudstack gui:

 

Cannot transit agent status with event AgentDisconnected for host 9, mangement server id is 91768838150,Unable to transition to a new state from Creating via AgentDisconnected

 

 

Any kind of help would be appreciated

 

Kind regards,

 

Alex


Alexander Sterk MEMBERS 06 October 2015 - 08:08 AM
12 0
Administrator
VM with Custom - Compute Offering
Avatar

VM with Custom - Compute Offering

Hello,

       I am unable create a Virtual Machine while  connecting via api call using Customized compute offering. It generates an error " Unable to verify user credentials ". Even if I am using right api and secret key. Same keys working for non customized offering. 

 

Request to cloudstack :

 

apiKey=njvsbc4XgcJy4LietGzvPxAIaVBE5LXFt7PvoORRcxog9Uc5R97Bo2Cmx2s9NgSSpMSyoBlal_4_8GTSkPjeSQ&command=deployVirtualMachine&details%5B0%5D.cpunumber=2&details%5B0%5D.cpuspeed=1000&details%5B0%5D.memory=1024&networkids=7444fc22-20ca-4065-9875-cbcbe91cfa42&response=json&serviceofferingid=d6b264db-3c3d-4cca-8698-4d6b66a46e35&templateid=2dfa0a67-d3eb-4cbd-b7f8-e26bec871b52&zoneid=6197512c-037f-4146-8e10-517be3d881f5&signature=T9mOyRuKO9KzG4mQpXsx%2B4gTO1o%3D

 

Error :

   {"deployvirtualmachineresponse":{"uuidList":[],"errorcode":401,"errortext":"unable to verify user credentials and/or request signature"}}


Mohanapriya R MEMBERS 13 November 2015 - 13:12 PM
9 0
Administrator
Lots of Templates showing in XenCenter
Avatar

Lots of Templates showing in XenCenter

Hi, can anyone assist

We have v4.2.0 running on 2 XenServer hosts, using several iSCSI storage repositories (Dell Equalogic)

I am trying to tidy up our current template offerings and i have noticed that there are more templates showing in Xencenter (when viewing the SR - Storage tab) than in the main GUI.

All that shows in XenCentrer is "Template <UUID>" however that UUID doesnt match up with the ID that the GUI has for each template

Is there any way i can match up what each template is so i know which can be deleted ? presumably some must be spare but even if they arent it would be useful to know which is which? They always say 0% on disk but the total does seem to take up space

Thanks

Jon


Matthew Darnell MEMBERS 28 April 2014 - 15:45 PM
8 0
Administrator
Why I can't login with default username/password:admin/password
Avatar

Why I can't login with default username/password:admin/password

I installed CloudPortal with package CloudPlatform-4.2.1-2-rhel6.4.tar.gz in CentOS6.0.

 

But when can't login it with username/password:admin/password

 

Who can help me to check the log to get the reason? Thanks a lot.

 

Here is the log:

=================================================

 

2014-01-29 09:08:13,290 DEBUG [cloud.server.ConfigurationServerImpl] (Timer-2:null) ConfigurationServer saved "10.67.255.116" as host.
2014-01-29 09:08:13,825 INFO  [cloud.server.ConfigurationServerImpl] (Timer-2:null) Processing updateSSLKeyStore
2014-01-29 09:08:13,886 INFO  [cloud.server.ConfigurationServerImpl] (Timer-2:null) SSL keystore located at /etc/cloudstack/management/cloudmanagementserver.keystore
2014-01-29 09:08:13,934 INFO  [cloud.server.ConfigurationServerImpl] (Timer-2:null) Processing updateKeyPairs
2014-01-29 09:08:13,934 INFO  [cloud.server.ConfigurationServerImpl] (Timer-2:null) Systemvm keypairs not found in database. Need to store them in the database
2014-01-29 09:08:13,935 DEBUG [utils.script.Script] (Timer-2:null) Executing: /bin/bash -c if [ -f /usr/share/cloud/portal/.ssh/id_rsa ]; then rm -f /usr/share/cloud/portal/.ssh/id_rsa; fi; ssh-keygen -t rsa -N '' -f /usr/share/cloud/portal/.ssh/id_rsa -q
2014-01-29 09:09:45,869 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===START===  10.67.15.188 -- GET  command=listCapabilities&response=json&sessionkey=null&_=1390986585779
2014-01-29 09:09:45,947 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===END===  10.67.15.188 -- GET  command=listCapabilities&response=json&sessionkey=null&_=1390986585779
2014-01-29 09:10:02,386 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===START===  10.67.15.188 -- GET  command=listCapabilities&response=json&sessionkey=null&_=1390986602303
2014-01-29 09:10:02,456 DEBUG [cloud.api.ApiServlet] (catalina-exec-22:null) ===END===  10.67.15.188 -- GET  command=listCapabilities&response=json&sessionkey=null&_=1390986602303
2014-01-29 09:10:10,126 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===START===  10.67.15.188 -- POST  
2014-01-29 09:10:10,143 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-8:null) Attempting to log in user: admin in domain 1
2014-01-29 09:10:10,143 DEBUG [server.auth.SHA256SaltedUserAuthenticator] (catalina-exec-8:null) Retrieving user: admin
2014-01-29 09:10:10,176 WARN  [server.auth.SHA256SaltedUserAuthenticator] (catalina-exec-8:null) The stored password for admin isn't in the right format for this authenticator
2014-01-29 09:10:10,485 DEBUG [server.auth.MD5UserAuthenticator] (catalina-exec-8:null) Retrieving user: admin
2014-01-29 09:10:10,494 DEBUG [server.auth.MD5UserAuthenticator] (catalina-exec-8:null) Password does not match
2014-01-29 09:10:10,497 DEBUG [server.auth.LDAPUserAuthenticator] (catalina-exec-8:null) Retrieving user: admin
2014-01-29 09:10:10,513 DEBUG [server.auth.LDAPUserAuthenticator] (catalina-exec-8:null) LDAP authenticator is not configured.
2014-01-29 09:10:10,514 DEBUG [server.auth.PlainTextUserAuthenticator] (catalina-exec-8:null) Retrieving user: admin
2014-01-29 09:10:10,521 DEBUG [server.auth.PlainTextUserAuthenticator] (catalina-exec-8:null) Password does not match
2014-01-29 09:10:10,524 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-8:null) Unable to authenticate user with username admin in domain 1
2014-01-29 09:10:10,529 INFO  [cloud.user.AccountManagerImpl] (catalina-exec-8:null) User admin is disabled/locked
2014-01-29 09:10:10,533 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-8:null) User: admin in domain 1 has failed to log in
2014-01-29 09:10:10,565 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===END===  10.67.15.188 -- POST  
 

 


Yongjun Tan MEMBERS 29 January 2014 - 09:16 AM
8 0
Filipe Santos
CloudPlataform License

Hi,

 

I recently downloaded and installed cloudplataform 4.2.1 and i checked install documentation and software and nothing about licenses or something like this.

 

i can use it normally?


8 0
Administrator
realhostip.com still being detected after uploading custom certificate
Avatar

realhostip.com still being detected after uploading custom certificate

I have uploaded my own SSL wildcard certificate using the instructions at:

 

http://docs.cloudstack.apache.org/projects/cloudstack-administration/en/4.4/systemvm.html#console-proxy

 

 

I have updated UI and changed the consoleproxy.url.domain in Global Settings.

 

However, browsers are still giving certificate errors showing

 

"The certificate is only valid for the following names: *.realhostip.com, realhostip.com"

 

When I generated my csr and purchased a wildcart cert I used my own domain name but browsers are still picking up realhostip info.

 

I've also tried destorying the console proxy VM and including both intermediate certs when updating SSL through the UI.

 

Thanks.

 


Ravi Bandara MEMBERS 06 February 2015 - 19:28 PM
7 0
Administrator
Unable to enter GUI after successful login
Avatar

Unable to enter GUI after successful login

Hello all,

 

Right after installing Cloud Platform on CentOs 6.5 I was able to login and go through the configuration steps. Once I was done with configuration, the GUI was working fine.

 

Now when I attempt to login, nothing happens. The logs show that login is successful, but I get the following errors right after:

 


 

 

2014-02-10 22:43:52,627 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===START===  192.168.1.186 -- GET  command=listCapabilities&response=json&sessionkey=5w3DJEp3oXUUmNuBl2FHfUyzYqw%3D&_=1392093828277
2014-02-10 22:43:52,678 DEBUG [cloud.api.ApiServlet] (catalina-exec-25:null) ===END===  192.168.1.186 -- GET  command=listCapabilities&response=json&sessionkey=5w3DJEp3oXUUmNuBl2FHfUyzYqw%3D&_=1392093828277
2014-02-10 22:43:58,628 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===START===  192.168.1.186 -- POST
2014-02-10 22:43:58,644 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-4:null) Attempting to log in user: admin in domain 1
2014-02-10 22:43:58,644 DEBUG [server.auth.SHA256SaltedUserAuthenticator] (catalina-exec-4:null) Retrieving user: admin
2014-02-10 22:43:58,721 DEBUG [cloud.user.AccountManagerImpl] (catalina-exec-4:null) User: admin in domain 1 has successfully logged in
2014-02-10 22:43:58,753 DEBUG [cloud.api.ApiServlet] (catalina-exec-4:null) ===END===  192.168.1.186 -- POST
2014-02-10 22:43:58,762 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===START===  192.168.1.186 -- GET  command=listCapabilities&response=json&sessionkey=kNoGEYsNvbMQv4f68lV6mO%2Fb%2B3c%3D&_=1392093834417
2014-02-10 22:43:58,772 DEBUG [cloud.api.ApiServer] (catalina-exec-8:null) The given command:listCapabilities does not exist or it is not available for user with id:2
2014-02-10 22:43:58,773 DEBUG [cloud.api.ApiServlet] (catalina-exec-8:null) ===END===  192.168.1.186 -- GET  command=listCapabilities&response=json&sessionkey=kNoGEYsNvbMQv4f68lV6mO%2Fb%2B3c%3D&_=1392093834417

 

 

 

Essentially I am stuck at the login page and can't get inside the GUI. The only change in the system/os was an IP address change (from DHCP to static). Could that be the issue? 

 

Also, it is worth mentioning that previous to this installation I had attempted to install Apache CloudStack on Debian 7. I had gotten the issues with wrong username/password during login that some other users experienced, but once I fixed it, I ended up with the same errors as I have shown you above.

 

Any ideas?

 

Thank you,

Rafael

 

 


Rafael Possamai MEMBERS 11 February 2014 - 04:58 AM
7 0
Administrator
Network configuration
Avatar

Network configuration

I need some help with network configuration. (I have attached a picture with resources)

 

 

Witch is the best configuration ?

 

Basic or Advanced configuration for Network ?

 

Primary and secondary storage how to ?

 

Attached Thumbnails

  • network.jpg

Hirlea Iulian MEMBERS 06 February 2014 - 01:34 AM
7 0
庄子智誉 / SHOJI,TOMOTAKA
About CloudPlatform 4.7 with VMware vSphere
Hi , Please teach me about CloudPlatform specification. Can I use the function of VMware vSphere shown below in combination with CloudPlatform? - VMware vSphere Storage DRS. (not Host DRS) https://www.vmware.com/products/vsphere/features/storage-drs - VMware Virtual Volumes (VVols). https://www.vmware.com/products/vsphere/features/virtual-volumes Kind regards, Tomotaka
6 -1
Administrator
vm system with agent state (-) none
Avatar

vm system with agent state (-) none

Hi community !

 

I use cloudstack 4.4 on centos with xenserver 6.2.

I created a advanced zone without security group. I have 3 nics :

- nics 1 for guest network and administration network.

- nics 2 for public network

- nics 3 for storage network.

 

I have a primary storage iscsi and my secondary storage locate on NFS server.

I followed installation instruction (vhd-utils, cloud-install-sys-tmplt ...)

 

When I working my zone, two vm created (secondary storage and console proxy) but agent state none (attach file 1)

 

I logged in Secondary Storage VM i try "ping 8.8.8.8" = ok but "ping www.google.fr" failed with DNS 8.8.8.8 of course.

 

When I created a advanced zone WITH security group. I don't have problems with System'vm. Agents of system'vm work normaly. I can download iso, template, to create VM ....

The only difference is to add the nic public network.

 

Where I can look for to help ?

 

Thanks for your help !

Attached Thumbnails

  • 1.PNG

Clément Mutz MEMBERS 30 July 2014 - 17:29 PM
6 0
Administrator
Cloudstack integration with RabbitMQ
Avatar

Cloudstack integration with RabbitMQ

Hi there, 

 

I have a Cloudstack instance running v4.3.

 

On a separate server, I have setup RabbitMQ, running on CentOS 6.5 by following the steps below:

 

rpm -ivh http://download.fedoraproject.org/pub/epel/6/$(arch)/epel-release-6-8.noarch.rpm

sudo yum install -y erlang

rpm --import http://www.rabbitmq.com/rabbitmq-signing-key-public.asc
sudo yum install -y rabbitmq-server.noarch

sudo chkconfig rabbitmq-server on

 

I've opened port 5672 through the firewall solely to the Cloudstack instance.

 

I have permitted remote users to connect with the following configuration:

 

cat /etc/rabbitmq/rabbitmq.config

[

  {rabbit, [{loopback_users, []}]}

].

 

I have removed the guest user, created a new user, provided that user with administrator privileges and allowed that user permissions over the default zone '/'

 

rabbitmqctl add_user rabbituser password123

rabbitmqctl set_user_tags rabbituser administrator

set_permissions -p / rabbituser ".*" ".*" ".*"

 

I have setup the following file on my Cloudstack instance and restarted Cloudstack:

 

cat /etc/cloudstack/management/componentContext.xml

<beans xmlns="http://www.springframework.org/schema/beans";

       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance";

       xmlns:context="http://www.springframework.org/schema/context";

       xmlns:aop="http://www.springframework.org/schema/aop";

       xsi:schemaLocation="http://www.springframework.org/schema/beans

                           http://www.springframework.org/schema/beans/spring-beans-3.0.xsd

                           http://www.springframework.org/schema/aop

                           http://www.springframework.org/schema/aop/spring-aop-3.0.xsd

                           http://www.springframework.org/schema/context

                           http://www.springframework.org/schema/context/spring-context-3.0.xsd";>

    <bean id="eventNotificationBus" class="org.apache.cloudstack.mom.rabbitmq.RabbitMQEventBus">

        <property name="name" value="eventNotificationBus"/>

        <property name="server" value="192.168.1.12"/>

        <property name="port" value="5672"/>

        <property name="username" value="rabbituser"/>

        <property name="password" value="password123"/>

        <property name="exchange" value="cloudstack-events"/>

    </bean>

</beans>  

 

sudo /etc/init.d/cloudstack-management stop && sudo /etc/init.d/cloudstack-management start

 

I can telnet from the Cloudstack instance to the RabbitMQ instance on port 5672 successfully.

 

I have then used tcpdump on both the Cloudstack instance and the RabbitMQ instance to look for traffic on port 5672 but there is none. Nothing is initiated by the Cloudstack instance.

 

Is /etc/cloudstack/management/componentContext.xml the correct location for this file? I cannot see anything erroneous in the cloudstack-management log file that shows it being picked up at all. 

 

Have I missed something or is there a more verbose guide available than those detailed at http://cloudstack.apache.org/docs/en-US/Apache_CloudStack/4.1.1/html/Admin_Guide/events.html ?

 

Thanks

 


P Green MEMBERS 25 July 2014 - 16:09 PM
6 0
Erik Godin
Multiple IPs per VM / Upgrading from 3.0.6 to 4.X

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.


6 0
Gert Jensen
Unable to create instances

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


6 0
Administrator
Cloudstack on Xenserver 6.5 support
Avatar

Cloudstack on Xenserver 6.5 support

Hi

 

Please let me know if the cloudstack supports Xenserver 6.5 fully.

What are the issues you faced with Xenserver 6.5

 

I am also testing Xenserver 6.5 on cloudstack. Please support me on this test at your end.


manish singh MEMBERS 05 March 2015 - 07:30 AM
5 0
Bill Freeman
Console Proxy VM disconnects but is still running

Has anyone seen the console VM disconnect from Cloud Platform Management randomly?  We are seeing it about once every week or so.  It does not notify of the agent disconnect or cause any other issues; if I restart the Console Proxy VM it restarts and reconnects as it should.

 

On CloudPlatform 4.3.0.1 on CentOS and XenServer 6.2 hypers.

   In the management log (IPs removed):

 

2015-01-05 05:35:05,334 DEBUG [c.c.h.AbstractInvestigatorImpl] (AgentTaskPool-8:ctx-98238002) host (x.x.x.x) has been successfully pinged, returning that host is up
2015-01-05 05:35:05,334 DEBUG [c.c.h.UserVmDomRInvestigator] (AgentTaskPool-8:ctx-98238002) ping from (1) to agent's host ip address (x.x.x.x) successful, returning that agent is disconnected
2015-01-05 05:35:05,335 DEBUG [c.c.h.HighAvailabilityManagerImpl] (AgentTaskPool-8:ctx-98238002) PingInvestigator was able to determine host 3 is in Disconnected
2015-01-05 05:35:05,335 INFO  [c.c.a.m.AgentManagerImpl] (AgentTaskPool-8:ctx-98238002) The state determined is Disconnected
2015-01-05 05:35:05,335 WARN  [c.c.a.m.AgentManagerImpl] (AgentTaskPool-8:ctx-98238002) Agent is disconnected but the host is still up: 3-v-1-VM
2015-01-05 05:35:05,336 INFO  [c.c.a.m.AgentManagerImpl] (AgentTaskPool-8:ctx-98238002) Host 3 is disconnecting with event AgentDisconnected
2015-01-05 05:35:05,357 DEBUG [c.c.a.m.AgentManagerImpl] (AgentTaskPool-8:ctx-98238002) The next status of agent 3is Alert, current status is Up
2015-01-05 05:35:05,358 DEBUG [c.c.a.m.AgentManagerImpl] (AgentTaskPool-8:ctx-98238002) Deregistering link for 3 with state Alert
2015-01-05 05:35:05,358 DEBUG [c.c.a.m.AgentManagerImpl] (AgentTaskPool-8:ctx-98238002) Remove Agent : 3
2015-01-05 05:35:05,358 DEBUG [c.c.a.m.ConnectedAgentAttache] (AgentTaskPool-8:ctx-98238002) Processing Disconnect.

 

It then proceeds to send disconnect info to the listeners.

 

Thanks

 

 


5 0
Administrator
Installing Template stuck
Avatar

Installing Template stuck

I have a template which is stuck with status Installing Template.
I tried restarting the SSVM, entire system and what not, each time it only resets the template download process, which goes through and always gets stuck on Installing Template. I have several other templates I created no problem. I just want to remove this stuck template install, but no matter what I try it can't remove it.

Please help someone.
Andrej Petkovski MEMBERS 05 January 2015 - 07:43 AM
5 0
Administrator
Xendestkop 7.5's connection to CloudPlatform
Avatar

Xendestkop 7.5's connection to CloudPlatform

Hi,

 

I have a question regarding the XD7.5's integration with Cloudplatform.

 

Suppose I have an on-premise VDI infrastructure with XD7.5 (including Controller/StoreFront) and Vsphere. All the virtual desktops were deployed using MCS in the site.

 

Now if I installed a CloudPlatform farm, adding the same vsphere hosts into Cloudplatform as the compute resouce. Then, can I create a new host connection with Cloudplatform type, to that Cloudplatform farm?

 

If so, will the existing desktops (independant of Cloudplatform) continued to be accessible  afterwards?

 

The idea is to understand how much effort is required to migrate existing on-premise VDI infrastructure to Cloudplatform, and whether it's worth a complete tear down and rebuild.

 

 

 

Thanks

 

Silvester


Silvester Du MEMBERS 22 July 2014 - 03:42 AM
5 0
Administrator
Virtual Router on VMware source NAT not applying
Avatar

Virtual Router on VMware source NAT not applying

Hi All,

 

I’ve encountered a strange issue whereby egress firewall rules don’t seem to apply to any CloudPlatform VRs that are running on our VMware cluster, whereas any CloudPlatform VRs running on our XenServer cluster work as expected (these are in the same and only zone). Even more strangely, port forwarding and ingress firewall rules do apply correctly in either scenario.

 

Has anyone encountered anything similar or has any troubleshooting tips for this? I have confirmed WAN connectivity, etc. from the VRs console and can see that there’s no matching entry in the iptables.

 

We are running Citrix CloudPlatform 4.3.0.1.

 

Any pointers would be greatly appreciated!

Thanks,

Eric


Eric Neumann MEMBERS 16 July 2014 - 10:21 AM
5 0
Administrator
Version 4.3 vs. ESXi hypervisor
Avatar

Version 4.3 vs. ESXi hypervisor

Hello, people.
We tested new version 4.3 on our ESXi both ver. 5.1\5.5 and get the same problem. 
After successfully installation dont start System VMs:
1. Created. 

2. Copy settings to them.

3. But instead of starting replace to another Host. 
4. In logs on first Host info something like "not found VM", deleting VM on second Host and and so cyclically.

 

Unfortunately, we do not have full logs.

 

 


Alexander Tsyrkun MEMBERS 04 June 2014 - 06:59 AM
5 0
James Meier
Cannot add VMFS datastore as primary storage

First, myself and my shop are new to clouplatform, so please bear with me.  We are however very experienced with VMware\ESXi.  I have a new cloudplatform environment being setup.  The hypervisors are VMware ESXi 5.1, and vCenter 5.1. During initial configuration, I selected advanced network deployment.  Running through the initial setup, all resources add successfully until we get to primary storage.   When attempting to add the Primary storage, an alert is immediately generated under general alerts on the dashboard stating "Host - Unable to attach storage poolxx to host1 (Timestamp)".  This error repeats each time the procedure is attempted.  The pool number increments up each time an attempt is made.  The management server lives on the same management vlan as the ESXi hosts.  The management server also has confirmed access to vCenter on 443.  This is not a vCenter permissions issue as we have tested this using full vCenter privs as well.  I have been working with Citrix support for well over a month on something I believe is probably a very simple issue.  Anyone have any ideas?  I would be happy to supply more information upon request.  Thanks in advance!


5 0
Gert Jensen
Juniper Site to Site VPN

Hello,

 

Have anybody tried to make a site to site vpn using a juniper srx (100) ?

 

and what are the parameters you are using both on Juniper and in Cloudstack

 

Does anybody know which log files i need to look in CloudStack ?

 

Kind regards

Gert


5 0
Gert Jensen
Backup of VMs

Hello,

 

I have been looking for a good backup solution, at the moment we are

backing up our vms inside using TSM, but are there any compatible backup

solution for cloudPlatform ?

 

I mean how to backup vm that is Off ?

 

Is it viable as a ISP to make a secondary backup of the customers system.

 

as i see it the snapshot are for the customers, but if the entire system breaks down, 

we are responseble for getting it up and running again.

 

Any thoughts ?

 

 

Kind regards

Gert


5 0
Neil Soyez
XenServer Pool Master Change

We Currently have a Cluster running XenServer hosts.   The pool master just had a hardware failure (The Storage NIC) and I need to eject it from the pool and designate a new master.   What needs to be done on the CloudPlatform end?   Currently, the master and the slaves can see each other just fine, as the management NIC is ok.   But, I notice that I cannot make any storage changes to instances.  (I assume because the master does not have access to the storage)

4 0
Administrator
Hyper-V Secondary
Avatar

Hyper-V Secondary

Greetings,

If you are adding Hyper-V to CloudPlatform, the requirement is SMB or local disk. If that is the case, its not NFS so mounting it to Management Server wouldn't be capable. So how would you add the system vm template if your secondary storage is an SMB hosted from a windows based file server?


Cliff Pinnock MEMBERS 28 January 2016 - 19:22 PM
4 0
Administrator
Unable to download iso template
Avatar

Unable to download iso template

Hello,

 

I got cloudstack 4.3 up and running with xenserver 6.2. When I try to add a iso file I get the error : "Timeout waiting for response from storage host".

 

The secondary nfs share is stored on the cloudstack server. The primary share is on my xenserver. I'm running a simple webserver where the ISO\s are stored. The cloudstack server made a get-request  on the web server, but thats about it.

 

I checked the management-server.log:

 

2015-10-28 11:46:56,467 DEBUG [c.c.a.ApiServlet] (catalina-exec-4:ctx-f9eef9a5) ===START===  10.1.10.252 -- GET  command=registerIso&response=json&sessionkey=nSYm%2BB%2B%2BaYCbnYIsxfw9V%2F4gapw%3D&name=centos&displayText=minimal&url=http%3A%2F%2F10.1.10.244%2Fiso%2FCentOS-6.7-x86_64-minimal.iso&zoneid=322a2d48-7669-40e5-b55c-7a6b48bd82ef&isextractable=true&bootable=true&osTypeId=ebac72a0-770f-11e5-aeb7-00155dd9580b&ispublic=true&isfeatured=true&_=1446029216419
2015-10-28 11:46:56,619 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl] (catalina-exec-4:ctx-f9eef9a5 ctx-c63a3970) template 202 is not in store:1, type:Image
2015-10-28 11:46:56,630 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl] (catalina-exec-4:ctx-f9eef9a5 ctx-c63a3970) template 202 is already in store:1, type:Image
2015-10-28 11:46:56,636 DEBUG [o.a.c.s.i.BaseImageStoreDriverImpl] (catalina-exec-4:ctx-f9eef9a5 ctx-c63a3970) Downloading template to data store 1
2015-10-28 11:46:56,642 WARN  [c.c.s.d.DownloadMonitorImpl] (catalina-exec-4:ctx-f9eef9a5 ctx-c63a3970) There is no secondary storage VM for downloading template to image store nfs://cloudstack.vboxx.nl/export/secondary
2015-10-28 11:46:56,645 DEBUG [o.a.c.s.i.TemplateDataFactoryImpl] (catalina-exec-4:ctx-f9eef9a5 ctx-c63a3970) template 202 is already in store:1, type:Image
2015-10-28 11:46:56,671 DEBUG [c.c.a.ApiServlet] (catalina-exec-4:ctx-f9eef9a5 ctx-c63a3970) ===END===  10.1.10.252 -- GET  command=registerIso&response=json&sessionkey=nSYm%2BB%2B%2BaYCbnYIsxfw9V%2F4gapw%3D&name=centos&displayText=minimal&url=http%3A%2F%2F10.1.10.244%2Fiso%2FCentOS-6.7-x86_64-minimal.iso&zoneid=322a2d48-7669-40e5-b55c-7a6b48bd82ef&isextractable=true&bootable=true&osTypeId=ebac72a0-770f-11e5-aeb7-00155dd9580b&ispublic=true&isfeatured=true&_=1446029216419
2015-10-28 11:47:01,101 DEBUG [c.c.a.ApiServlet] (catalina-exec-19:ctx-7622a86a) ===START===  10.1.10.252 -- GET  command=listIsos&isofilter=self&id=db8c2136-3e5f-40bd-b8c6-a9f900017f49&zoneid=322a2d48-7669-40e5-b55c-7a6b48bd82ef&response=json&sessionkey=nSYm%2BB%2B%2BaYCbnYIsxfw9V%2F4gapw%3D&_=1446029221097
2015-10-28 11:47:01,130 DEBUG [c.c.a.ApiServlet] (catalina-exec-19:ctx-7622a86a ctx-69892895) ===END===  10.1.10.252 -- GET  command=listIsos&isofilter=self&id=db8c2136-3e5f-40bd-b8c6-a9f900017f49&zoneid=322a2d48-7669-40e5-b55c-7a6b48bd82ef&response=json&sessionkey=nSYm%2BB%2B%2BaYCbnYIsxfw9V%2F4gapw%3D&_=1446029221097
2015-10-28 11:47:01,198 DEBUG [c.c.a.ApiServlet] (catalina-exec-20:ctx-689d97c4) ===START===  10.1.10.252 -- GET  command=listOsTypes&response=json&sessionkey=nSYm%2BB%2B%2BaYCbnYIsxfw9V%2F4gapw%3D&_=1446029221147
2015-10-28 11:47:01,474 DEBUG [c.c.a.ApiServlet] (catalina-exec-20:ctx-689d97c4 ctx-8490dc76) ===END===  10.1.10.252 -- GET  command=listOsTypes&response=json&sessionkey=nSYm%2BB%2B%2BaYCbnYIsxfw9V%2F4gapw%3D&_=1446029221147
 

Troubleshooting steps so far:

 

- I have configured secstorage.allowed.internal.sites with the IP-adress from the webserver

-Added a second drive (20GB) for the secondary storage. I thought my secondary nfs share didnt had enought space for the iso's.

-Logged on the system-VM and could ping to WAN- and LAN-devices.

 

 

Any kind of help would be appreciated

 

Kind regards,

 

Alex


Alexander Sterk MEMBERS 28 October 2015 - 12:58 PM
4 0
Administrator
Migrate VMs from XenServer 6.2.0 to CCP 4.3.0.2
Avatar

Migrate VMs from XenServer 6.2.0 to CCP 4.3.0.2

We have a current XenServer/XenCenter setup where we have 4 virtual machines.  We now have a CloudPlatform environment setup on different hardware, but also using XenServer 6.2.0 as the hypervisor.  I need to somehow get these VMs over to the CCP environment and I'm finding it hard to figure out the best way to do this.  The docs seem to block me in whatever effort I think I need to do.

 

FIrst of all, I need to do any exporting, etc. from the XenServer CLI.  There's no way I can download hundreds of GB of files and reupload them.  From the CLI, I should be able to transfer them directly from the old environment to a location in the new environment where I can put them where they need to be.

 

So, according to the admin guide for CCP, chapter 13 talks about uploading templates.  In the UI RegisterTemplates dialog, the only format option available for a XenServer hypervisor is a VHD format file.  So, how can I get a VHD file out of my existing VM hard disks?

 

If I do a xe vm-export, it will only create a ".xva" file.  If I do a xe vdi-export, it appears what it will export is considered a "raw vdi" file.  But I'm not sure what the means or what format it is in.  In our current environment, all the VDI's appear to be individual LVM volumes.

 

It's hard for me to believe that with both XenServer and CCP being Citrix products, that it can be so difficult to transfer VMs from one environment to the other.

 

I hope someone can help me figure out what to do.

 


Bruce Hellstrom MEMBERS 05 February 2015 - 03:02 AM
4 0
 

Top Contributors