2013-11-06 11:49:02,424 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager
Timer:null) Completed resetting hosts suitable for reconnect2013-11-06
11:49:02,424 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager Timer:null)
Acquiring hosts for clusters already owned by this management server2013-11-06
11:49:02,426 DEBUG [host.dao.HostDaoImpl] (ClusteredAgentManager Timer:null)
Completed acquiring hosts for clusters already owned by this management
server2013-11-06 11:49:02,427 DEBUG [host.dao.HostDaoImpl]
(ClusteredAgentManager Timer:null) Acquiring hosts for clusters not owned by
any management server2013-11-06 11:49:02,427 DEBUG [host.dao.HostDaoImpl]
(ClusteredAgentManager Timer:null) Completed acquiring hosts for clusters not
owned by any management server2013-11-06 11:49:24,626 DEBUG
[storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is
ready to launch secondary storage VM2013-11-06 11:49:24,633 INFO
[cloud.secstorage.PremiumSecondaryStorageManagerImpl] (secstorage-1:null) No
running secondary storage vms found in datacenter id=1, starting one2013-11-06
11:49:24,640 INFO [storage.secondary.SecondaryStorageManagerImpl]
(secstorage-1:null) No stopped secondary storage vm is available, need to
allocate a new secondary storage vm2013-11-06 11:49:24,643 DEBUG
[storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Assign
secondary storage vm from a newly started instance for request from data center
: 12013-11-06 11:49:24,664 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Found existing network configuration for offering [Network
Offering [6-Guest-DefaultSharedNetworkOfferingWithSGService]:
Ntwk[204|Guest|6]2013-11-06 11:49:24,664 DEBUG
[cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for
Acct[3d802b46-41c8-11e3-88f8-46223c2e0d56-system]2013-11-06 11:49:24,670 DEBUG
[cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network
configuration for offering [Network Offering
[3-Control-System-Control-Network]: Ntwk[202|Control|3]2013-11-06 11:49:24,670
DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for
Acct[3d802b46-41c8-11e3-88f8-46223c2e0d56-system]2013-11-06 11:49:24,676 DEBUG
[cloud.network.NetworkManagerImpl] (secstorage-1:null) Found existing network
configuration for offering [Network Offering
[2-Management-System-Management-Network]: Ntwk[201|Management|2]2013-11-06
11:49:24,676 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null)
Releasing lock for Acct[3d802b46-41c8-11e3-88f8-46223c2e0d56-system]2013-11-06
11:49:24,682 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Found
existing network configuration for offering [Network Offering
[4-Storage-System-Storage-Network]: Ntwk[203|Storage|4]2013-11-06 11:49:24,682
DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null) Releasing lock for
Acct[3d802b46-41c8-11e3-88f8-46223c2e0d56-system]2013-11-06 11:49:24,691 DEBUG
[cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocating entries for
VM: VM[SecondaryStorageVm|s-3-VM]2013-11-06 11:49:24,694 DEBUG
[cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocating nics for
VM[SecondaryStorageVm|s-3-VM]2013-11-06 11:49:24,694 DEBUG
[cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm
VM[SecondaryStorageVm|s-3-VM] in network Ntwk[204|Guest|6] with requested
profile NicProfile[0-0-null-null-null2013-11-06 11:49:24,711 DEBUG
[cloud.network.NetworkManagerImpl] (secstorage-1:null) Allocating nic for vm
VM[SecondaryStorageVm|s-3-VM] in network Ntwk[202|Control|3] with requested
profile null2013-11-06 11:49:24,717 DEBUG [cloud.network.NetworkManagerImpl]
(secstorage-1:null) Allocating nic for vm VM[SecondaryStorageVm|s-3-VM] in
network Ntwk[201|Management|2] with requested profile null2013-11-06
11:49:24,724 DEBUG [cloud.network.NetworkManagerImpl] (secstorage-1:null)
Allocating nic for vm VM[SecondaryStorageVm|s-3-VM] in network
Ntwk[203|Storage|4] with requested profile null2013-11-06 11:49:24,739 DEBUG
[cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocating disks for
VM[SecondaryStorageVm|s-3-VM]2013-11-06 11:49:24,751 DEBUG
[cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is
ready to launch console proxy2013-11-06 11:49:24,752 DEBUG
[cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Expand
console proxy standby capacity for zone testxenserver2013-11-06 11:49:24,754
DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Allocation
completed for VM: VM[SecondaryStorageVm|s-3-VM]2013-11-06 11:49:24,754 INFO
[cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Found a
stopped console proxy, bring it up to running pool. proxy vm id : 22013-11-06
11:49:24,755 DEBUG [cloud.alert.SecondaryStorageVmAlertAdapter]
(secstorage-1:null) received secondary storage vm alert2013-11-06 11:49:24,755
DEBUG [cloud.alert.SecondaryStorageVmAlertAdapter] (secstorage-1:null) New
secondary storage vm created, zone: testxenserver, secStorageVm: s-3-VM, public
IP: null, private IP: null2013-11-06 11:49:24,766 DEBUG
[cloud.capacity.CapacityManagerImpl] (consoleproxy-1:null) VM state transitted
from :Stopped to Starting with event: StartRequestedvm's original host id: null
new host id: null host id before state transition: null2013-11-06 11:49:24,766
DEBUG [cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Successfully
transitioned to start state for VM[ConsoleProxy|v-2-VM] reservation id =
767d371d-d0cb-49e7-87f4-6fdadcd630322013-11-06 11:49:24,777 DEBUG
[cloud.capacity.CapacityManagerImpl] (secstorage-1:null) VM state transitted
from :Stopped to Starting with event: StartRequestedvm's original host id: null
new host id: null host id before state transition: null2013-11-06 11:49:24,777
DEBUG [cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Successfully
transitioned to start state for VM[SecondaryStorageVm|s-3-VM] reservation id =
c27f9a42-ac9c-4d37-836d-2ca68135ca782013-11-06 11:49:24,779 DEBUG
[cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Trying to deploy VM,
vm has dcId: 1 and podId: null2013-11-06 11:49:24,779 DEBUG
[cloud.vm.VirtualMachineManagerImpl] (consoleproxy-1:null) Deploy avoids pods:
null, clusters: null, hosts: null2013-11-06 11:49:24,781 DEBUG
[cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Trying to deploy VM,
vm has dcId: 1 and podId: null2013-11-06 11:49:24,781 DEBUG
[cloud.vm.VirtualMachineManagerImpl] (secstorage-1:null) Deploy avoids pods:
null, clusters: null, hosts: null2013-11-06 11:49:24,783 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (consoleproxy-1:null) Deploy
avoids pods: null, clusters: null, hosts: null2013-11-06 11:49:24,784 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) Deploy avoids
pods: null, clusters: null, hosts: null2013-11-06 11:49:24,784 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) Deploy avoids
pods: null, clusters: null, hosts: null2013-11-06 11:49:24,784 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (consoleproxy-1:null)
DeploymentPlanner allocation algorithm:
com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_426e0ca0@7488b7f12013-11-06
11:49:24,784 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(consoleproxy-1:null) Trying to allocate a host and storage pools from dc:1,
pod:null,cluster:null, requested cpu: 500, requested ram: 10737418242013-11-06
11:49:24,784 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(consoleproxy-1:null) Is ROOT volume READY (pool already allocated)?:
No2013-11-06 11:49:24,785 DEBUG [cloud.deploy.FirstFitPlanner]
(consoleproxy-1:null) Searching all possible resources under this Zone:
12013-11-06 11:49:24,786 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(secstorage-1:null) DeploymentPlanner allocation algorithm:
com.cloud.deploy.FirstFitPlanner_EnhancerByCloudStack_426e0ca0@7488b7f12013-11-06
11:49:24,786 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(secstorage-1:null) Trying to allocate a host and storage pools from dc:1,
pod:null,cluster:null, requested cpu: 500, requested ram: 2684354562013-11-06
11:49:24,786 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(secstorage-1:null) Is ROOT volume READY (pool already allocated)?:
No2013-11-06 11:49:24,786 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Searching all possible resources under this Zone:
12013-11-06 11:49:24,786 DEBUG [cloud.deploy.FirstFitPlanner]
(consoleproxy-1:null) Listing clusters in order of aggregate capacity, that
have (atleast one host with) enough CPU and RAM capacity under this Zone:
12013-11-06 11:49:24,788 DEBUG [cloud.deploy.FirstFitPlanner]
(secstorage-1:null) Listing clusters in order of aggregate capacity, that have
(atleast one host with) enough CPU and RAM capacity under this Zone:
12013-11-06 11:49:24,800 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(consoleproxy-1:null) Checking resources in Cluster: 2 under Pod: 22013-11-06
11:49:24,800 DEBUG [allocator.impl.FirstFitAllocator]
(consoleproxy-1:FirstFitRoutingAllocator) Looking for hosts in dc: 1 pod:2
cluster:22013-11-06 11:49:24,802 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) Checking
resources in Cluster: 2 under Pod: 22013-11-06 11:49:24,802 DEBUG
[allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator)
Looking for hosts in dc: 1 pod:2 cluster:22013-11-06 11:49:24,805 DEBUG
[allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator)
FirstFitAllocator has 0 hosts to check for allocation: []2013-11-06
11:49:24,807 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) FirstFitAllocator has 0 hosts to check
for allocation: []2013-11-06 11:49:24,810 DEBUG
[allocator.impl.FirstFitAllocator] (consoleproxy-1:FirstFitRoutingAllocator)
Found 0 hosts for allocation after prioritization: []2013-11-06 11:49:24,810
DEBUG [allocator.impl.FirstFitAllocator]
(consoleproxy-1:FirstFitRoutingAllocator) Looking for speed=500Mhz,
Ram=10242013-11-06 11:49:24,810 DEBUG [allocator.impl.FirstFitAllocator]
(consoleproxy-1:FirstFitRoutingAllocator) Host Allocator returning 0 suitable
hosts2013-11-06 11:49:24,810 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(consoleproxy-1:null) No suitable hosts found2013-11-06 11:49:24,810 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (consoleproxy-1:null) No suitable
hosts found under this Cluster: 22013-11-06 11:49:24,812 DEBUG
[allocator.impl.FirstFitAllocator] (secstorage-1:FirstFitRoutingAllocator)
Found 0 hosts for allocation after prioritization: []2013-11-06 11:49:24,812
DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Looking for speed=500Mhz,
Ram=2562013-11-06 11:49:24,812 DEBUG [allocator.impl.FirstFitAllocator]
(secstorage-1:FirstFitRoutingAllocator) Host Allocator returning 0 suitable
hosts2013-11-06 11:49:24,812 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(secstorage-1:null) No suitable hosts found2013-11-06 11:49:24,812 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (secstorage-1:null) No suitable
hosts found under this Cluster: 22013-11-06 11:49:24,815 DEBUG
[cloud.deploy.DeploymentPlanningManagerImpl] (consoleproxy-1:null) Could not
find suitable Deployment Destination for this VM under any clusters,
returning.2013-11-06 11:49:24,815 DEBUG [cloud.deploy.FirstFitPlanner]
(consoleproxy-1:null) Searching all possible resources under this Zone:
12013-11-06 11:49:24,817 DEBUG [cloud.deploy.FirstFitPlanner]
(consoleproxy-1:null) Listing clusters in order of aggregate capacity, that
have (atleast one host with) enough CPU and RAM capacity under this Zone:
12013-11-06 11:49:24,819 DEBUG [cloud.deploy.DeploymentPlanningManagerImpl]
(secstorage-1:null) Could not find suitable Deployment Destination for this VM
under any clusters, returning.2013-11-06 11:49:24,819 DEBUG
[cloud.deploy.FirstFitPlanner] (secstorage-1:null) Searching all possible
resources under this Zone: 12013-11-06 11:49:24,821 DEBUG
[cloud.deploy.FirstFitPlanner] (secstorage-1:null) Listing clusters in order of
aggregate capacity, that have (atleast one host with) enough CPU and RAM
capacity under this Zone: 12013-11-06 11:49:24,823 DEBUG
[cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) Removing from the
clusterId list these clusters from avoid set: [2]2013-11-06 11:49:24,823 DEBUG
[cloud.deploy.FirstFitPlanner] (consoleproxy-1:null) No clusters found after
removing disabled clusters and clusters in avoid list, returning.2013-11-06
11:49:24,825 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) Removing
from the clusterId list these clusters from avoid set: [2]2013-11-06
11:49:24,826 DEBUG [cloud.deploy.FirstFitPlanner] (secstorage-1:null) No
clusters found after removing disabled clusters and clusters in avoid list,
returning.2013-11-06 11:49:24,834 DEBUG [cloud.capacity.CapacityManagerImpl]
(consoleproxy-1:null) VM state transitted from :Starting to Stopped with event:
OperationFailedvm's original host id: null new host id: null host id before
state transition: null2013-11-06 11:49:24,835 DEBUG
[cloud.capacity.CapacityManagerImpl] (secstorage-1:null) VM state transitted
from :Starting to Stopped with event: OperationFailedvm's original host id:
null new host id: null host id before state transition: null2013-11-06
11:49:24,838 WARN [cloud.consoleproxy.ConsoleProxyManagerImpl]
(consoleproxy-1:null) Exception while trying to start console
proxycom.cloud.exception.InsufficientServerCapacityException: Unable to create
a deployment for VM[ConsoleProxy|v-2-VM]Scope=interface
com.cloud.dc.DataCenter; id=1
