Welcome, Guest
Username: Password: Remember me
Archived CloudStack Forums for reference purposes please direct all future inquiries to the Apache CloudStack mailing lists.

incubator.apache.org/cloudstack/mailing-lists.html
  • Page:
  • 1
  • 2

TOPIC: Unable to Launch Virtual Machine (VM) using ISO with CloudStack 3.0.1

Unable to Launch Virtual Machine (VM) using ISO with CloudStack 3.0.1 1 year 5 months ago #10736

  • jamesbjackson
  • jamesbjackson's Avatar
  • OFFLINE
  • Senior Boarder
  • Posts: 43
  • Thank you received: 8
  • Karma: 2
Hi Everyone,

Firstly I have included my entire management-server.log file in a zip, it is 11MB uncompressed..
Creating the virtual machine would one of the last few entries in the log as was the last action before create a clone to add to this post.

Background: I just rebuilt my development to be using the lastest version of cloudstack ready for getting our production cloud to a deployable state and was just going through and checking i got everything setup correctly and can deploy a virtual machine from the suppled template that comes with CloudStack. Now I rebuilt it many times without issue was this took me by surprise with really only CloudStack 3.0.1 and XenServer 6.0.2 being the only difference. I am deploying Basic Networking with Security Groups. Which I setup via the wizard when you first startup CloudStack.

Now I downloaded a ISO with no reported issues. I downloaded CentOS 6.2 (64bit) Minimal.

I keep getting a error saying it was unable to be deployed. Now if you scan through the log file your run into another issue which I address with the Virtual Router did not startup correctly so rebooted that which fix that issue.

Here is my details of my development cloud and what has worked previously.

Management Server: Running CloudStack, MySQL & Internal DNS Server
2x Hypervisors Running XenServer 6.0.2 with the Security Groups Addition added.
1x Enterprise NAS server running both my primary and secondary storage via NFS

All running within the same network connected to the same switch. I have also included a screenshot to below I that it should not be a capacity issue. I tried using both small and medium service offering as well.



I can grab a complete snapshot of my database as well if that helps?

Any help would be very much appreciated.

James
Attachments:
The administrator has disabled public write access.

Re: Unable to Launch Virtual Machine (VM) using ISO with CloudStack 3.0.1 1 year 5 months ago #10737

  • jamesbjackson
  • jamesbjackson's Avatar
  • OFFLINE
  • Senior Boarder
  • Posts: 43
  • Thank you received: 8
  • Karma: 2
Here is the snapshot in question from the Management Server Log.
2012-05-08 13:24:45,582 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) VM is being started in podId: 1
2012-05-08 13:24:45,586 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Network id=204 is already implemented
2012-05-08 13:24:45,677 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Changing active number of nics for network id=204 on 1
2012-05-08 13:24:45,738 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Asking JuniperSRX to prepare for Nic[15-9-5c09ed4f-b5e8-4d89-a65a-6b5afa2139ce-192.168.0.183]
2012-05-08 13:24:45,742 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Asking Netscaler to prepare for Nic[15-9-5c09ed4f-b5e8-4d89-a65a-6b5afa2139ce-192.168.0.183]
2012-05-08 13:24:45,747 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Asking F5BigIp to prepare for Nic[15-9-5c09ed4f-b5e8-4d89-a65a-6b5afa2139ce-192.168.0.183]
2012-05-08 13:24:45,750 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Asking VirtualRouter to prepare for Nic[15-9-5c09ed4f-b5e8-4d89-a65a-6b5afa2139ce-192.168.0.183]
2012-05-08 13:24:45,758 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-19:job-29) Starting a router for Ntwk[204|Guest|5] in datacenter:com.cloud.dc.DataCenterVO$$EnhancerByCGLIB$$a7885c9f@1
2012-05-08 13:24:45,773 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-19:job-29) Applying dhcp entry in network Ntwk[204|Guest|5]
2012-05-08 13:24:45,792 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530862: Sending  { Cmd , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 100111, [{"routing.DhcpEntryCommand":{"vmMac":"06:c7:04:00:00:4a","vmIpAddress":"192.168.0.183","vmName":"fe484091-3607-4035-b314-abf064d9e42d","defaultRouter":"192.168.0.1","defaultDns":"192.168.0.177","accessDetails":{"router.guest.ip":"192.168.0.177","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.1.178"},"wait":0}}] }
2012-05-08 13:24:45,792 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530862: Executing:  { Cmd , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 100111, [{"routing.DhcpEntryCommand":{"vmMac":"06:c7:04:00:00:4a","vmIpAddress":"192.168.0.183","vmName":"fe484091-3607-4035-b314-abf064d9e42d","defaultRouter":"192.168.0.1","defaultDns":"192.168.0.177","accessDetails":{"router.guest.ip":"192.168.0.177","zone.network.type":"Basic","router.name":"r-4-VM","router.ip":"169.254.1.178"},"wait":0}}] }
2012-05-08 13:24:45,793 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-221:null) Seq 5-2094530862: Executing request
2012-05-08 13:24:48,299 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-221:null) Seq 5-2094530862: Response Received: 
2012-05-08 13:24:48,300 DEBUG [agent.transport.Request] (DirectAgent-221:null) Seq 5-2094530862: Processing:  { Ans: , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 110, [{"Answer":{"result":true,"wait":0}}] }
2012-05-08 13:24:48,300 DEBUG [cloud.vm.VirtualMachineManagerImpl] (DirectAgent-221:null) Cleanup succeeded. Details null
2012-05-08 13:24:48,300 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530862: Received:  { Ans: , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 110, { Answer } }
2012-05-08 13:24:48,300 DEBUG [agent.manager.AgentAttache] (DirectAgent-221:null) Seq 5-2094530862: No more commands found
2012-05-08 13:24:48,300 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Cleanup succeeded. Details null
2012-05-08 13:24:48,321 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (Job-Executor-19:job-29) Applying userdata and password entry in network Ntwk[204|Guest|5]
2012-05-08 13:24:48,346 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530863: Sending  { Cmd , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 100111, [{"routing.SavePasswordCommand":{"password":"fnirq_cnffjbeq","vmIpAddress":"192.168.0.183","vmName":"fe484091-3607-4035-b314-abf064d9e42d","accessDetails":{"router.guest.ip":"192.168.0.177","zone.network.type":"Basic","router.ip":"169.254.1.178","router.name":"r-4-VM"},"wait":0}},{"routing.VmDataCommand":{"vmIpAddress":"192.168.0.183","vmName":"fe484091-3607-4035-b314-abf064d9e42d","accessDetails":{"router.guest.ip":"192.168.0.177","zone.network.type":"Basic","router.ip":"169.254.1.178","router.name":"r-4-VM"},"wait":0}}] }
2012-05-08 13:24:48,347 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530863: Executing:  { Cmd , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 100111, [{"routing.SavePasswordCommand":{"password":"fnirq_cnffjbeq","vmIpAddress":"192.168.0.183","vmName":"fe484091-3607-4035-b314-abf064d9e42d","accessDetails":{"router.guest.ip":"192.168.0.177","zone.network.type":"Basic","router.ip":"169.254.1.178","router.name":"r-4-VM"},"wait":0}},{"routing.VmDataCommand":{"vmIpAddress":"192.168.0.183","vmName":"fe484091-3607-4035-b314-abf064d9e42d","accessDetails":{"router.guest.ip":"192.168.0.177","zone.network.type":"Basic","router.ip":"169.254.1.178","router.name":"r-4-VM"},"wait":0}}] }
2012-05-08 13:24:48,347 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-393:null) Seq 5-2094530863: Executing request
2012-05-08 13:24:57,532 DEBUG [storage.secondary.SecondaryStorageManagerImpl] (secstorage-1:null) Zone 1 is ready to launch secondary storage VM
2012-05-08 13:24:57,677 DEBUG [cloud.consoleproxy.ConsoleProxyManagerImpl] (consoleproxy-1:null) Zone 1 is ready to launch console proxy
2012-05-08 13:24:57,829 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Snapshot scheduler.poll is being called at 2012-05-08 12:24:57 GMT
2012-05-08 13:24:57,831 DEBUG [storage.snapshot.SnapshotSchedulerImpl] (SnapshotPollTask:null) Got 0 snapshots to be executed at 2012-05-08 12:24:57 GMT
2012-05-08 13:24:57,926 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-393:null) Seq 5-2094530863: Response Received: 
2012-05-08 13:24:57,927 DEBUG [agent.transport.Request] (DirectAgent-393:null) Seq 5-2094530863: Processing:  { Ans: , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 110, [{"Answer":{"result":true,"wait":0}},{"Answer":{"result":true,"wait":0}}] }
2012-05-08 13:24:57,927 DEBUG [cloud.vm.VirtualMachineManagerImpl] (DirectAgent-393:null) Cleanup succeeded. Details null
2012-05-08 13:24:57,927 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530863: Received:  { Ans: , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 110, { Answer, Answer } }
2012-05-08 13:24:57,927 DEBUG [cloud.vm.VirtualMachineManagerImpl] (DirectAgent-393:null) Cleanup succeeded. Details null
2012-05-08 13:24:57,927 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Cleanup succeeded. Details null
2012-05-08 13:24:57,928 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Cleanup succeeded. Details null
2012-05-08 13:24:57,927 DEBUG [agent.manager.AgentAttache] (DirectAgent-393:null) Seq 5-2094530863: No more commands found
2012-05-08 13:24:57,928 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Asking Ovs to prepare for Nic[15-9-5c09ed4f-b5e8-4d89-a65a-6b5afa2139ce-192.168.0.183]
2012-05-08 13:24:57,928 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Asking ExternalDhcpServer to prepare for Nic[15-9-5c09ed4f-b5e8-4d89-a65a-6b5afa2139ce-192.168.0.183]
2012-05-08 13:24:57,935 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Asking BareMetal to prepare for Nic[15-9-5c09ed4f-b5e8-4d89-a65a-6b5afa2139ce-192.168.0.183]
2012-05-08 13:24:57,935 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Asking SecurityGroupProvider to prepare for Nic[15-9-5c09ed4f-b5e8-4d89-a65a-6b5afa2139ce-192.168.0.183]
2012-05-08 13:24:57,948 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-19:job-29) Checking if we need to prepare 1 volumes for VM[User|i-2-9-VM]
2012-05-08 13:24:57,948 DEBUG [cloud.storage.StorageManagerImpl] (Job-Executor-19:job-29) No need to recreate the volume: Vol[10|vm=9|ROOT], since it already has a pool assigned: 200, adding disk to VM
2012-05-08 13:24:58,074 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530864: Sending  { Cmd , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 100111, [{"StartCommand":{"vm":{"id":9,"name":"i-2-9-VM","bootloader":"CD","type":"User","cpus":1,"speed":500,"minRam":536870912,"maxRam":536870912,"arch":"x86_64","os":"CentOS 6.0 (64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"vncPassword":"6dd1deff1095f692","params":{},"disks":[{"id":10,"name":"ROOT-9","mountPoint":"/primary","path":"bcc33775-6a29-455e-a4ee-3b574f79b523","size":5368709120,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"6ded49c3-3907-3146-84b5-b635b4bd0656","deviceId":0},{"id":9,"name":"CentOS 6.2 (64-bit) Minimal","path":"nfs://secondary.cloud.local/secondary/template/tmpl//2/205//205-2-79105d41-0b5b-377c-b0d1-f1820003a2ff.iso","size":0,"type":"ISO","storagePoolType":"ISO","deviceId":3,"guestOsType":"CentOS 6.0 (64-bit)"}],"nics":[{"deviceId":0,"networkRateMbps":200,"defaultNic":true,"ip":"192.168.0.183","netmask":"255.255.255.0","gateway":"192.168.0.1","mac":"06:c7:04:00:00:4a","dns1":"192.168.0.1","dns2":"8.8.8.8","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://untagged","isolationUri":"ec2://untagged","isSecurityGroupEnabled":false}]},"wait":0}}] }
2012-05-08 13:24:58,075 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530864: Executing:  { Cmd , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 100111, [{"StartCommand":{"vm":{"id":9,"name":"i-2-9-VM","bootloader":"CD","type":"User","cpus":1,"speed":500,"minRam":536870912,"maxRam":536870912,"arch":"x86_64","os":"CentOS 6.0 (64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"vncPassword":"6dd1deff1095f692","params":{},"disks":[{"id":10,"name":"ROOT-9","mountPoint":"/primary","path":"bcc33775-6a29-455e-a4ee-3b574f79b523","size":5368709120,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"6ded49c3-3907-3146-84b5-b635b4bd0656","deviceId":0},{"id":9,"name":"CentOS 6.2 (64-bit) Minimal","path":"nfs://secondary.cloud.local/secondary/template/tmpl//2/205//205-2-79105d41-0b5b-377c-b0d1-f1820003a2ff.iso","size":0,"type":"ISO","storagePoolType":"ISO","deviceId":3,"guestOsType":"CentOS 6.0 (64-bit)"}],"nics":[{"deviceId":0,"networkRateMbps":200,"defaultNic":true,"ip":"192.168.0.183","netmask":"255.255.255.0","gateway":"192.168.0.1","mac":"06:c7:04:00:00:4a","dns1":"192.168.0.1","dns2":"8.8.8.8","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://untagged","isolationUri":"ec2://untagged","isSecurityGroupEnabled":false}]},"wait":0}}] }
2012-05-08 13:24:58,076 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-286:null) Seq 5-2094530864: Executing request
2012-05-08 13:24:58,220 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-286:null) 1. The VM i-2-9-VM is in Starting state.
2012-05-08 13:24:58,239 WARN  [xen.resource.CitrixResourceBase] (DirectAgent-286:null) Catch Exception: class java.util.NoSuchElementException due to java.util.NoSuchElementException
java.util.NoSuchElementException
	at java.util.LinkedHashMap$LinkedHashIterator.nextEntry(LinkedHashMap.java:392)
	at java.util.LinkedHashMap$KeyIterator.next(LinkedHashMap.java:401)
	at com.cloud.hypervisor.xen.resource.XenServer56FP1Resource.createVmFromTemplate(XenServer56FP1Resource.java:125)
	at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:1094)
	at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:467)
	at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:69)
	at com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:187)
	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:1110)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
	at java.lang.Thread.run(Thread.java:679)
2012-05-08 13:24:58,241 WARN  [xen.resource.CitrixResourceBase] (DirectAgent-286:null) Unable to start i-2-9-VM due to 
java.util.NoSuchElementException
	at java.util.LinkedHashMap$LinkedHashIterator.nextEntry(LinkedHashMap.java:392)
	at java.util.LinkedHashMap$KeyIterator.next(LinkedHashMap.java:401)
	at com.cloud.hypervisor.xen.resource.XenServer56FP1Resource.createVmFromTemplate(XenServer56FP1Resource.java:125)
	at com.cloud.hypervisor.xen.resource.CitrixResourceBase.execute(CitrixResourceBase.java:1094)
	at com.cloud.hypervisor.xen.resource.CitrixResourceBase.executeRequest(CitrixResourceBase.java:467)
	at com.cloud.hypervisor.xen.resource.XenServer56Resource.executeRequest(XenServer56Resource.java:69)
	at com.cloud.agent.manager.DirectAgentAttache$Task.run(DirectAgentAttache.java:187)
	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:1110)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
	at java.lang.Thread.run(Thread.java:679)
2012-05-08 13:24:58,242 DEBUG [xen.resource.CitrixResourceBase] (DirectAgent-286:null) The VM is in stopped state, detected problem during startup : i-2-9-VM
2012-05-08 13:24:58,242 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-286:null) Seq 5-2094530864: Response Received: 
2012-05-08 13:24:58,244 DEBUG [agent.transport.Request] (DirectAgent-286:null) Seq 5-2094530864: Processing:  { Ans: , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 110, [{"StartAnswer":{"vm":{"id":9,"name":"i-2-9-VM","bootloader":"CD","type":"User","cpus":1,"speed":500,"minRam":536870912,"maxRam":536870912,"arch":"x86_64","os":"CentOS 6.0 (64-bit)","bootArgs":"","rebootOnCrash":false,"enableHA":false,"limitCpuUse":false,"vncPassword":"6dd1deff1095f692","params":{},"disks":[{"id":10,"name":"ROOT-9","mountPoint":"/primary","path":"bcc33775-6a29-455e-a4ee-3b574f79b523","size":5368709120,"type":"ROOT","storagePoolType":"NetworkFilesystem","storagePoolUuid":"6ded49c3-3907-3146-84b5-b635b4bd0656","deviceId":0},{"id":9,"name":"CentOS 6.2 (64-bit) Minimal","path":"nfs://secondary.cloud.local/secondary/template/tmpl//2/205//205-2-79105d41-0b5b-377c-b0d1-f1820003a2ff.iso","size":0,"type":"ISO","storagePoolType":"ISO","deviceId":3,"guestOsType":"CentOS 6.0 (64-bit)"}],"nics":[{"deviceId":0,"networkRateMbps":200,"defaultNic":true,"ip":"192.168.0.183","netmask":"255.255.255.0","gateway":"192.168.0.1","mac":"06:c7:04:00:00:4a","dns1":"192.168.0.1","dns2":"8.8.8.8","broadcastType":"Vlan","type":"Guest","broadcastUri":"vlan://untagged","isolationUri":"ec2://untagged","isSecurityGroupEnabled":false}]},"result":false,"details":"Unable to start i-2-9-VM due to ","wait":0}}] }
2012-05-08 13:24:58,244 WARN  [cloud.vm.VirtualMachineManagerImpl] (DirectAgent-286:null) Cleanup failed due to Unable to start i-2-9-VM due to 
2012-05-08 13:24:58,244 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530864: Received:  { Ans: , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 110, { StartAnswer } }
2012-05-08 13:24:58,244 DEBUG [agent.manager.AgentAttache] (DirectAgent-286:null) Seq 5-2094530864: No more commands found
2012-05-08 13:24:58,244 WARN  [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Cleanup failed due to Unable to start i-2-9-VM due to 
2012-05-08 13:24:58,306 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterMonitor-1:null) Found 0 running routers. 
2012-05-08 13:24:58,307 DEBUG [network.router.VirtualNetworkApplianceManagerImpl] (RouterStatusMonitor-1:null) Found 0 routers. 
2012-05-08 13:24:58,414 DEBUG [cloud.network.ExternalLoadBalancerUsageManagerImpl] (ExternalNetworkMonitor-1:null) External load balancer devices stats collector is running...
2012-05-08 13:24:58,416 INFO  [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Unable to start VM on Host[-5-Routing] due to Unable to start i-2-9-VM due to 
2012-05-08 13:24:58,452 INFO  [cloud.ha.HighAvailabilityManagerImpl] (HA-1:null) checking health of usage server
2012-05-08 13:24:58,454 DEBUG [cloud.ha.HighAvailabilityManagerImpl] (HA-1:null) usage server running? true, heartbeat: Tue May 08 13:24:28 BST 2012
2012-05-08 13:24:58,482 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Cleaning up resources for the vm VM[User|i-2-9-VM] in Starting state
2012-05-08 13:24:58,486 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530865: Sending  { Cmd , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 100111, [{"StopCommand":{"isProxy":false,"vmName":"i-2-9-VM","wait":0}}] }
2012-05-08 13:24:58,486 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530865: Executing:  { Cmd , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 100111, [{"StopCommand":{"isProxy":false,"vmName":"i-2-9-VM","wait":0}}] }
2012-05-08 13:24:58,486 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-393:null) Seq 5-2094530865: Executing request
2012-05-08 13:24:58,752 INFO  [xen.resource.CitrixResourceBase] (DirectAgent-393:null) VM does not exist on XenServer5bbaba61-ca61-4f27-914c-d678112d4ac5
2012-05-08 13:24:58,752 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-393:null) Seq 5-2094530865: Response Received: 
2012-05-08 13:24:58,753 DEBUG [agent.transport.Request] (DirectAgent-393:null) Seq 5-2094530865: Processing:  { Ans: , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 110, [{"StopAnswer":{"vncPort":0,"bytesSent":0,"bytesReceived":0,"result":true,"details":"VM does not exist","wait":0}}] }
2012-05-08 13:24:58,753 DEBUG [cloud.vm.VirtualMachineManagerImpl] (DirectAgent-393:null) Cleanup succeeded. Details VM does not exist
2012-05-08 13:24:58,753 DEBUG [agent.transport.Request] (Job-Executor-19:job-29) Seq 5-2094530865: Received:  { Ans: , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 110, { StopAnswer } }
2012-05-08 13:24:58,753 DEBUG [agent.manager.AgentAttache] (DirectAgent-393:null) Seq 5-2094530865: No more commands found
2012-05-08 13:24:58,753 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Cleanup succeeded. Details VM does not exist
2012-05-08 13:24:58,864 DEBUG [cloud.network.NetworkManagerImpl] (Job-Executor-19:job-29) Changing active number of nics for network id=204 on -1
2012-05-08 13:24:58,939 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Successfully cleanued up resources for the vm VM[User|i-2-9-VM] in Starting state
2012-05-08 13:24:58,942 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Root volume is ready, need to place VM in volume's cluster
2012-05-08 13:24:58,943 DEBUG [cloud.vm.VirtualMachineManagerImpl] (Job-Executor-19:job-29) Vol[10|vm=9|ROOT] is READY, changing deployment plan to use this pool's dcId: 1 , podId: 1 , and clusterId: 1
2012-05-08 13:24:58,944 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) DeploymentPlanner allocation algorithm: random
2012-05-08 13:24:58,944 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Trying to allocate a host and storage pools from dc:1, pod:1,cluster:1, requested cpu: 500, requested ram: 536870912
2012-05-08 13:24:58,944 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Is ROOT volume READY (pool already allocated)?: Yes
2012-05-08 13:24:58,944 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Searching resources only under specified Cluster: 1
2012-05-08 13:24:58,948 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-291:null) Ping from 5
2012-05-08 13:24:58,956 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Checking resources in Cluster: 1 under Pod: 1
2012-05-08 13:24:58,956 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Calling HostAllocators to find suitable hosts
2012-05-08 13:24:58,956 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-19:job-29 FirstFitRoutingAllocator) Looking for hosts in dc: 1  pod:1  cluster:1
2012-05-08 13:24:58,960 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-19:job-29 FirstFitRoutingAllocator) FirstFitAllocator has 2 hosts to check for allocation: [Host[-1-Routing], Host[-5-Routing]]
2012-05-08 13:24:58,966 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-19:job-29 FirstFitRoutingAllocator) Found 2 hosts for allocation after prioritization: [Host[-1-Routing], Host[-5-Routing]]
2012-05-08 13:24:58,966 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-19:job-29 FirstFitRoutingAllocator) Looking for speed=500Mhz, Ram=512
2012-05-08 13:24:58,966 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-19:job-29 FirstFitRoutingAllocator) Host name: compute1.cloud.local, hostId: 1 is in avoid set, skipping this and trying other available hosts
2012-05-08 13:24:58,966 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-19:job-29 FirstFitRoutingAllocator) Host name: compute2.cloud.local, hostId: 5 is in avoid set, skipping this and trying other available hosts
2012-05-08 13:24:58,966 DEBUG [allocator.impl.FirstFitAllocator] (Job-Executor-19:job-29 FirstFitRoutingAllocator) Host Allocator returning 0 suitable hosts
2012-05-08 13:24:58,966 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) No suitable hosts found
2012-05-08 13:24:58,966 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) No suitable hosts found under this Cluster: 1
2012-05-08 13:24:58,966 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Could not find suitable Deployment Destination for this VM under any clusters, returning. 
2012-05-08 13:24:58,968 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) DeploymentPlanner allocation algorithm: random
2012-05-08 13:24:58,968 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Trying to allocate a host and storage pools from dc:1, pod:null,cluster:null, requested cpu: 500, requested ram: 536870912
2012-05-08 13:24:58,968 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Is ROOT volume READY (pool already allocated)?: No
2012-05-08 13:24:58,968 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Searching all possible resources under this Zone: 1
2012-05-08 13:24:58,970 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Listing clusters in order of aggregate capacity, that have (atleast one host with) enough CPU and RAM capacity under this Zone: 1
2012-05-08 13:24:58,971 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) CPUOverprovisioningFactor considered: 1.0
2012-05-08 13:24:58,976 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) Removing from the clusterId list these clusters from avoid set: [1]
2012-05-08 13:24:58,976 DEBUG [cloud.deploy.FirstFitPlanner] (Job-Executor-19:job-29) No clusters found after removing disabled clusters and clusters in avoid list, returning.
2012-05-08 13:24:59,195 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-19:job-29) VM state transitted from :Starting to Stopped with event: OperationFailedvm's original host id: null new host id: null host id before state transition: 5
2012-05-08 13:24:59,204 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-19:job-29) Hosts's actual total CPU: 20224 and CPU after applying overprovisioning: 20224
2012-05-08 13:24:59,204 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-19:job-29) release cpu from host: 5, old used: 2000,reserved: 0, actual total: 20224, total with overprovisioning: 20224; new used: 1500,reserved:0; movedfromreserved: false,moveToReserveredfalse
2012-05-08 13:24:59,204 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-19:job-29) release mem from host: 5, old used: 2013265920,reserved: 0, total: 7907848704; new used: 1476395008,reserved:0; movedfromreserved: false,moveToReserveredfalse
2012-05-08 13:24:59,432 DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-19:job-29) 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
2012-05-08 13:24:59,606 DEBUG [agent.manager.AgentManagerImpl] (AgentManager-Handler-10:null) Ping from 3
2012-05-08 13:25:00,002 INFO  [api.commands.DeployVMCmd] (Job-Executor-19:job-29) com.cloud.exception.InsufficientServerCapacityException: Unable to create a deployment for VM[User|i-2-9-VM]Scope=interface com.cloud.dc.DataCenter; id=1
2012-05-08 13:25:00,002 WARN  [cloud.api.ApiDispatcher] (Job-Executor-19:job-29) class com.cloud.api.ServerApiException : Unable to create a deployment for VM[User|i-2-9-VM]
2012-05-08 13:25:00,003 DEBUG [cloud.async.AsyncJobManagerImpl] (Job-Executor-19:job-29) Complete async job-29, jobStatus: 2, resultCode: 530, result: com.cloud.api.response.ExceptionResponse@114d79cd
2012-05-08 13:25:01,725 DEBUG [cloud.async.AsyncJobManagerImpl] (catalina-exec-6:null) Async job-29 completed
2012-05-08 13:25:11,044 DEBUG [cloud.server.StatsCollector] (StatsCollector-2:null) StorageCollector is running...
2012-05-08 13:25:11,126 DEBUG [cloud.vm.VirtualMachineManagerImpl] (AgentManager-Handler-12:null) Cleanup succeeded. Details null
2012-05-08 13:25:11,126 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 4-236257497: Received:  { Ans: , MgmtId: 115129171910725, via: 4, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2012-05-08 13:25:11,126 DEBUG [cloud.vm.VirtualMachineManagerImpl] (StatsCollector-2:null) Cleanup succeeded. Details null
2012-05-08 13:25:11,138 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-202:null) Seq 5-2094530866: Executing request
2012-05-08 13:25:11,764 DEBUG [agent.manager.DirectAgentAttache] (DirectAgent-202:null) Seq 5-2094530866: Response Received: 
2012-05-08 13:25:11,765 DEBUG [cloud.vm.VirtualMachineManagerImpl] (DirectAgent-202:null) Cleanup succeeded. Details null
2012-05-08 13:25:11,765 DEBUG [agent.transport.Request] (StatsCollector-2:null) Seq 5-2094530866: Received:  { Ans: , MgmtId: 115129171910725, via: 5, Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
2012-05-08 13:25:11,765 DEBUG [cloud.vm.VirtualMachineManagerImpl] (StatsCollector-2:null) Cleanup succeeded. Details null
 

There seems to a strange unexpected exception happening...
The administrator has disabled public write access.

Re: Unable to Launch Virtual Machine (VM) using ISO with CloudStack 3.0.1 1 year 5 months ago #10769

  • ke4qqq
  • ke4qqq's Avatar
  • OFFLINE
  • Administrator
  • Posts: 620
  • Thank you received: 69
  • Karma: 22
I have no idea what's causing that - weird error - you might want to post to This email address is being protected from spambots. You need JavaScript enabled to view it. - and ask there.
The administrator has disabled public write access.

Re: Unable to Launch Virtual Machine (VM) using ISO with CloudStack 3.0.1 1 year 5 months ago #10778

  • jamesbjackson
  • jamesbjackson's Avatar
  • OFFLINE
  • Senior Boarder
  • Posts: 43
  • Thank you received: 8
  • Karma: 2
I think what would be great is in the OS list in the GUI for the downloaded ISO, what OS should I tell CloudStack that it is? I tried CentOS 6 but this is the error I got.. When I tried other Linux I got slightly further.. So I think it to do with the ISO os type and XenServer not liking it, is there any documentation about how use custom ISO with CloudStack and what OS type they should be using?
The administrator has disabled public write access.

Re: Unable to Launch Virtual Machine (VM) using ISO with CloudStack 3.0.1 1 year 4 months ago #11186

  • danineto
  • danineto's Avatar
  • OFFLINE
  • Fresh Boarder
  • Posts: 1
  • Karma: 0
Hi!

I have the same error and I haven't any idea why this occurs.
The administrator has disabled public write access.

Re: Unable to Launch Virtual Machine (VM) using ISO with CloudStack 3.0.1 1 year 2 months ago #12001

  • wangbo
  • wangbo's Avatar
  • OFFLINE
  • Fresh Boarder
  • Posts: 13
  • Karma: 0
hi,I have same problem. Can you tell me how to resolve the problem with you? Thank you!
The administrator has disabled public write access.
  • Page:
  • 1
  • 2
Time to create page: 0.320 seconds

Open@Citrix

Citrix supports the open source community via developer support and evangeslism. We have a number of developers and evangelists that participate actively in the open source community in Apache Cloudstack, OpenDaylight, Xen Project and XenServer. We also conduct educational activities via the Build A Cloud events held all over the world. 

Connect