CloudPlatform 4.x

 
 
Administrator
Get Size of VMSnapshot (VMWare)
Avatar

Get Size of VMSnapshot (VMWare)

Hello!

I have a small question - how ca I get the size of a VMSnapshot, that is made via a VMWare ESX call?

I've found one function, that calculates the amount, during the creation of a Snapshot (see the code snippet), but it seems, that I cannot get this value anywhere after the creation

Is there any entry of this in a database, or in a log file?


A.

package com.cloud.hypervisor.vmware.manager;

 

 

private long getVMSnapshotChainSize(VmwareContext context, VmwareHypervisorHost hyperHost,

String fileName, String poolUuid, String exceptFileName)
throws Exception{
long size = 0;
ManagedObjectReference morDs = HypervisorHostHelper.findDatastoreWithBackwardsCompatibility(hyperHost, poolUuid);
DatastoreMO dsMo = new DatastoreMO(context, morDs);
HostDatastoreBrowserMO browserMo = dsMo.getHostDatastoreBrowserMO();
String datastorePath = "[" + dsMo.getName() + "]";
HostDatastoreBrowserSearchSpec searchSpec = new HostDatastoreBrowserSearchSpec();
FileQueryFlags fqf = new FileQueryFlags();
fqf.setFileSize(true);
fqf.setFileOwner(true);
fqf.setModification(true);
searchSpec.setDetails(fqf);
searchSpec.setSearchCaseInsensitive(false);
searchSpec.getMatchPattern().add(fileName);
ArrayList<HostDatastoreBrowserSearchResults> results = browserMo.
searchDatastoreSubFolders(datastorePath, searchSpec);
for(HostDatastoreBrowserSearchResults result : results){
if (result != null) {
List<FileInfo> info = result.getFile();
for (FileInfo fi : info) {
if(exceptFileName != null && fi.getPath().contains(exceptFileName)) {
continue;
} else {
size = size + fi.getFileSize();
}
}
}
}
return size;
}

 

 

 


Artjoms Petrovs MEMBERS 06 March 2014 - 15:40 PM
2 0
Administrator
Template from VM on ESX hypervisor
Avatar

Template from VM on ESX hypervisor

Hello all!
We install a CloudStack platform ver.4.2.1 based on ESX hypervisors.

The First question - how to create Template from existisng VMs?

In this doc:
http://cloudstack.apache.org/docs/en-US/Apache_CloudStack/4.2.0/html/Admin_Guide/create-template-from-existing-vm.html

said: Click Create Template

 

But where this Button located?


Alexander Tsyrkun MEMBERS 04 March 2014 - 08:10 AM
3 0
Administrator
unable to xenserver as host in CloudPlatform
Avatar

unable to xenserver as host in CloudPlatform

When I am trying to add the host, it gives a failure saying "failed to add host"

the ipaddress and username/password are given correctly

 

any ideas what will be the issue here


Bhaskar Rao B MEMBERS 26 February 2014 - 06:51 AM
2 0
Administrator
Error in installation
Avatar

Error in installation

hi all  :) while executing the following command I get warnings

oneadmin@ubuntu:~/maven$ - mvn clean install -P developer,systemvm

[WARNING] The requested profile "developer" could not be activated because it does not exist.

[WARNING] The requested profile "systemvm" could not be activated because it does not exist.
 
But it shows that the build is successful. What cloud be the mistake here?
If I ignore the warning and proceed with the next step, the following happens.
oneadmin@ubuntu:~/maven$ mvn -P developer -pl developer -Ddeploydb
[INFO] Scanning for projects...
[ERROR] Could not find the selected project in the reactor: developer -> [Help 1]
[ERROR] 
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR] 
[ERROR] For more information about the errors and possible solutions, please read the following articles:
 

 

Thanks for the help  :)

 

 


Uma Sankar MEMBERS 22 February 2014 - 03:53 AM
1 0
Administrator
Template VHD disappears when download symlink is cleaned up
Avatar

Template VHD disappears when download symlink is cleaned up

In 4.2.1, I've found that the template VHD file gets removed from the template directory after it has been downloaded. The issue occurs when the secondary storage server is cleaning up the symlink in /var/www/html/. Along with removing the symlink, the actual vhd is also removed. From looking at the Apache CloudStack source, it looks like maybe the SSVM is seeing the template as a volume and thinks that it is removing a hard link. Has anyone come across this problem, or is there anything that can be done to work around it?


Bill Rich MEMBERS 19 February 2014 - 22:52 PM
0 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
Gert Jensen
adding nics to existing installation

Hello,

 

Does anybody know how to add additional nics to an existing installation.

 

We only had 8 nics bonded in 4 nics now we could use another for additional network.

 

But i do not think we can do it using the GUI.

 

I do not know how to do it in a curl command.

 

If you have an example of an command i can use i will be greatfull.. :)

 

I assume that i am not stock on initial config. ?

 

Kind regards

Gert


0 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
Administrator
Energy measurement
Avatar

Energy measurement

Hi all :)  I'm new to cloudstack. I would like to know if it is possible to view the energy consumed by a VM? My project deals with minimizing the energy consumed by the VMs.

Thanks in advance  :)


Uma Sankar MEMBERS 05 February 2014 - 06:31 AM
4 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
Administrator
How to access CloudPlatform via HTTP API?
Avatar

How to access CloudPlatform via HTTP API?

I am tring to get some data from CloudPlatform via HTTP API.

 

I have some questions:

 

1. signature method is same as CPBM BSS API? If not, what is the difference?

 

2. Is it correct get API key and secret_key from cloud.user that username is "admin-api"

 

3. If 2 is right, how to convert the secret_key?

 

 

 

Thanks in advance!


Yongjun Tan MEMBERS 28 January 2014 - 09:42 AM
1 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
Setting a lease period for a vm
Avatar

Setting a lease period for a vm

Hello ,

 

I need to set a lease time for a virtual instance and this instance should get deleted post the lease time period.

 

Is there a way i can achieve this. 

 

Thank you for your help.

 

Best Regards

 

Faustina


Faustina Glory MEMBERS 22 January 2014 - 06:43 AM
2 0
Administrator
windows 2008 r2 template
Avatar

windows 2008 r2 template

i just followed the instruction on how to create a windows template  https://cloudstack.apache.org/docs/en-US/Apache_CloudStack/4.0.2/html/Admin_Guide/create-windows-template.html

 

but after the sysprep i am clueless whats the next step? thanks


aristotle ahito MEMBERS 21 January 2014 - 06:24 AM
1 0
Administrator
"Configuring guest traffic" - Something went wrong
Avatar

"Configuring guest traffic" - Something went wrong

I got the following error when running the CloudPlatform™ installation wizard:

 

  • Configuring guest traffic

Something went wrong; you may go back and correct any errors:
Incorrect number

 
 
Where do I start looking for the problem?

 


Guy Korland MEMBERS 20 January 2014 - 09:20 AM
4 0
Filipe Santos
Unable do delete isos

Hi,

 

I'm stuck in a problem.

i try to upload an iso via http and its failed. so the iso is unavailable and error status: no route to host.
if i try to delete the broken iso return an error and cannot be deleted

Messages:
Failed to delete iso
 

In event

Error while deleting iso. ISO Id: 210

 

 


2 0
Gert Jensen
Problem uploading templates.. no logs..?

Hello,

 

Now i have uploaded 3 templates it went fine.

now my 4th template, that webnt wrong.

 

I get errors in the cloudstack-management.log and cataline.out, i do not know if this is related.

 

from the computer i am using as a webdav server to be able to upload the template, i can see traffic,

for app. 0,5-1 hours 500Mbit/s the template is 223Gb, and it is VHD.

 

I will be glad for any suggestions, as i can not see where it went wrong, if you need other logfiles, please say so..

 

I have an snapshot in allocated state, which i can not delete, i do not know if this i related.

 

Please, help

 

Kind regards

Gert

 

 

Attached Files


4 0
Gert Jensen
Why choose Citrix Cloudplatform over vCloud

Hello,

 

I just want some input.

 

We are a small company, trying out CloudPlatform, on 2 servers..

small setup for testing and trying 3 small customers in production.

 

We do have some problems with Cloudplatform, and in this perspective

i think we need to buy support, this is where i am thinking what?

 

vmware is 30% of the prices Citrix is asking.

 

is it worth paying so much extra, as i see it there are no support forums that work, so support is not optional, and vmware has been on the market +10 years...

 

What are your thoughts...

 

am i calculating this correct.. am i missing something, are there anything cloudstack deos better ?

 

any thoughts will be great..

 

Kind regards

Gert


4 0
Gert Jensen
Why choose Citrix Cloudplatform over vCloud

Hello,

 

I thougt i had deleted this..


0 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
Gregoire Lamodiere
ssvm - cannot make dns resolution

Hi all, 

 

I'm curently in the processs of implementing cloudplateform trial version.

 

Eveything went fine until now :

 

installation ok, first instance creation from template (centos) ok, etc.

 

I am now trying to upload a iso. And my problem started at that point.

I had different erros while trying to upload my iso.

 

I then connected to the ssvm to start the check script that fails on dns resolution.

 

For my test, I defined 8.8.8.8 as dns servers.

I can ping 8.8.8.8 or any other ip, but no DNS resolution can be performed.

If I try nslookup or dig, I always have a connection time out.

 

Do you have any idea ?

 

Regards.

 

Greg


1 0
Simon Murphy
Bulk VM import

Hi,

 

Has anyone come up with a way to perform bulk VM migrations into CCP? Currently we are using DT Move however this is an expensive method.

 

Is there anything on the roadmap for bulk import, particularly when using vSphere?

 

Cheers,

Simon


0 0
Simon Murphy
Hardware Firewall for VPC

Hi,

 

Is it possible to offload firewall, SourceNAT and inter-VLAN routing functions to hardware devices for VPC networks or is this supported only for Isolated Networks?

 

Thanks,

Simon

 


1 0
Simon Murphy
Isolated Guest Network with no VR

Hi,

 

I would like to know if anyone has managed to configure a service offering for a guest network that has no connectivity to the outside world(ie a guest net with no VR).

 

I would like customer to be able to provision a totally isolated network that has no internet connectivity or connecvivity to any other networks so that they can use their own softwre firewall to provide the inter-VLAN routing and security services.

 

I can set up a shared netwrok with no services, however I cant have overlapping IP ranges with this network type. I can also configure the guest network with no services, however when I go to deploy a network from the offering it is not avaiable for selection.

 

Really hoping someone has this working as it is a pretty core requiement for a customer.

 

Thanks,

Simon

 


2 0
Administrator
adding iscsi primary storage
Avatar

adding iscsi primary storage

Hey guys,

 

Really hoping someone can help me out here.

 

I have installed CloudPlatform 4.2 and am trying to add iscsi storage as primary storage.

 

It keeps failing and just says unable to add storage.

 

Checking the logs on the management server, I can see that its failing authentication to connect to our Dell EqualLogic.

 

It doesnt ask me for the chaps username or password anywhere in the add storage screen, so how do I add credentials to this connection so that it wont fail?

 

Many thanks in advance!

 

Jonny

Attached Thumbnails

  • primarystoragefail.jpg
  • primarystoragefail1.jpg

John Muckley MEMBERS 03 December 2013 - 13:30 PM
1 0
Administrator
Configuring CloudPlatform
Avatar

Configuring CloudPlatform

All,

 

I'm new to the Citrix and Linux environment but never the less I'm in the process of trying the CloudPlatform  trial installation. The problem I'm been stuck with now is the mounting of the shared secondary server just before the download of the VM templates.

 

I'm using a box running OS Redhat 6.5 Enterprises. I'm following the full installation guide as my Management Server is on it's on machine on it's own node. My NFS server is running Centos on VM with Xenserver 6.x as the OS. From the management server I'm trying to mount : mount -t nfs nfs:/nfs/export/secondary /mnt/secondary but now I'm receiving a message: mount.nfs: Connection timed out

 

 

before I was getting the message permission denied from server but after a reboot of the NFS and management server I'm now at the connection timed out- while trying to mnt the drive.

 

 

Any help is appreciated

 

 


Carl Williams MEMBERS 01 December 2013 - 17:07 PM
2 0
Administrator
Replacing Virtual Router with custom virtual appliance template
Avatar

Replacing Virtual Router with custom virtual appliance template

Is it possible to create a network offering, which would use a custom virtual appliance instead of the default Debian template for the virtual router?

I'd like to provide a premium network offering, with a commercial security gateway/UTM virtual appliance as network provider. Ie the FortiGate UTM provides VPN, NAT, DNS, DHCP, routing and other network features similar to Virtual Router, but also offers security features like anitispam, virus scanning, deep packet inspection, IPS etc.

I can create a network like this
Internet -> ACS VR-> Fortigate TM VM -> other VM
But not sure how can force all the traffic from the VMs to go via the FortiGate. So having the UTM as a custom network provider would ensure it?

The environment is XenServer 6.2 and CP 4.2.1 and Advanced Networking

Thanks,
Michal


Michal Rodzos MEMBERS 30 November 2013 - 12:09 PM
1 0
Administrator
Campo 4.2 - Cannot create snapshot.
Avatar

Campo 4.2 - Cannot create snapshot.

I've changed the global setting of :

kvm.snapshot.enabled true

 

Then restarted the management server.  ( also restarted cloud-agent on all computes, just to make sure. )

 

And yet, I'm not able to create snapshot from running VM.

 

Log shows -

2013-11-27 10:59:09,346 INFO  [cloud.api.ApiServer] (http-6443-exec-5:null) VM snapshot is not enabled for hypervisor type: KVM
 

 

google shows this

 

 

Regards,

Yossi


Yossi Ovadia MEMBERS 27 November 2013 - 09:42 AM
4 0
Gert Jensen
How to create VPC Private gateway

Hello,

 

I have read the instructions on how to create a VPC private gateway, but i can not figure what to put 

in the different boxes.. :)

 

I have a customer they are using VLAN12 (on the cloud), with an IP range 10.17.20.0/24, and now i have put in a 1gb direct connection to their office i have created a vlan40 an plugged it in, the iP address on this router is: 10.17.20.4/24.

 

  • Physical Network: The physical network you have created in the zone.
  • IP Address: The IP address associated with the VPC gateway.
  • Gateway: The gateway through which the traffic is routed to and from the VPC.
  • Netmask: The netmask associated with the VPC gateway.
  • VLAN: The VLAN associated with the VPC gateway.

I know Physical network, vlan but other than this, i can not figure it out.

 

Kind regards

Gert

 


1 0
 

Top Contributors