> -----Original Message-----
> From: Kamil Gardziejczyk [mailto:belussi...@gmail.com]
> Sent: Monday, October 01, 2012 7:01 AM
> To: cloudstack-dev@incubator.apache.org
> Subject: Unable to start VM with build 314 and FirstFitRoutingAllocator.
>
> When i`m trying to start vm i get info:
>
> [java] DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-7:) Seq
> 1-908394505: No more commands found
> [java] DEBUG [agent.transport.Request] (Job-Executor-2:job-11) Seq
> 1-908394505: Received: { Ans: , MgmtId: 130580009884, via: 1, Ver: v1,
> Flags: 110, { StartAnswer, CheckSshAnswer, GetDomRVersionAnswer,
> Answer } }
> [java] WARN [network.router.VirtualNetworkApplianceManagerImpl]
> (Job-Executor-2:job-11) Unable to get the template/scripts version of
> router r-4-TEST due to: GetDomRVersionCmd failed
> [java] INFO [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-2:job-11) The guru did not like the answers so stopping
> VM[DomainRouter|r-4-TEST]
> [java] DEBUG [agent.transport.Request] (Job-Executor-2:job-11) Seq
> 1-908394507: Sending { Cmd , MgmtId: 130580009884, via: 1, Ver: v1,
> Flags:
> 100111, [{"StopCommand":{"isProxy":false,"vmName":"r-4-
> TEST","wait":0}}] }
> [java] DEBUG [agent.transport.Request] (AgentManager-Handler-9:)
> Seq
> 1-908394507: Processing: { Ans: , MgmtId: 130580009884, via: 1, Ver:
> v1,
> Flags: 110, [{"StopAnswer":{"vncPort":0,"result":true,"wait":0}}] }
> [java] DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-9:)
> Seq 1-908394507: No more commands found
> [java] DEBUG [agent.transport.Request] (Job-Executor-2:job-11) Seq
> 1-908394507: Received: { Ans: , MgmtId: 130580009884, via: 1, Ver: v1,
> Flags: 110, { StopAnswer } }
> [java] ERROR [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-2:job-11) Failed to start instance VM[DomainRouter|r-4-
> TEST]
> [java] com.cloud.utils.exception.ExecutionException: Unable to
> start
> VM[DomainRouter|r-4-TEST] due to error in finalizeStart, not retrying
> [java] at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManag
> erImpl.java:814)
> [java] at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.
> java:472)
> [java] at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.start(Virtu
> alNetworkApplianceManagerImpl.java:2352)
> [java] at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.startVirtua
> lRouter(VirtualNetworkApplianceManagerImpl.java:1597)
> [java] at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.startRouter
> s(VirtualNetworkApplianceManagerImpl.java:1697)
> [java] at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.deployVirtu
> alRouterInGuestNetwork(VirtualNetworkApplianceManagerImpl.java:1675)
> [java] at
> com.cloud.network.element.VirtualRouterElement.prepare(VirtualRouterEle
> ment.java:205)
> [java] at
> com.cloud.network.NetworkManagerImpl.prepareElement(NetworkManagerImpl.
> java:2055)
> [java] at
> com.cloud.network.NetworkManagerImpl.prepareNic(NetworkManagerImpl.java
> :2171)
> [java] at
> com.cloud.network.NetworkManagerImpl.prepare(NetworkManagerImpl.java:21
> 12)
> [java] at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManag
> erImpl.java:752)
> [java] at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.
> java:472)
> [java] at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.ja
> va:2929)
> [java] at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.ja
> va:2601)
> [java] at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.ja
> va:2589)
> [java] at
> com.cloud.event.ActionEventCallback.intercept(ActionEventCallback.java:
> 36)
> [java] at
> com.cloud.api.commands.DeployVMCmd.execute(DeployVMCmd.java:330)
> [java] at
> com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:138)
> [java] at
> com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:432)
> [java] at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> [java] at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
> [java] at
> java.util.concurrent.FutureTask.run(FutureTask.java:166)
> [java] at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.ja
> va:1110)
> [java] at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.j
> ava:603)
> [java] at java.lang.Thread.run(Thread.java:679)
> [java] DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-2:job-11) Cleaning up resources for the vm
> VM[DomainRouter|r-4-TEST] in Starting state
> [java] DEBUG [agent.transport.Request] (Job-Executor-2:job-11) Seq
> 1-908394508: Sending { Cmd , MgmtId: 130580009884, via: 1, Ver: v1,
> Flags:
> 100111, [{"StopCommand":{"isProxy":false,"vmName":"r-4-
> TEST","wait":0}}] }
> [java] DEBUG [agent.transport.Request] (AgentManager-Handler-4:)
> Seq
> 1-908394508: Processing: { Ans: , MgmtId: 130580009884, via: 1, Ver:
> v1,
> Flags: 110, [{"StopAnswer":{"vncPort":0,"result":true,"wait":0}}] }
> [java] DEBUG [agent.manager.AgentAttache] (AgentManager-Handler-4:)
> Seq 1-908394508: No more commands found
>
>
> and:
>
> [java] DEBUG [cloud.capacity.CapacityManagerImpl] (Job-Executor-2:job-
> 11)
> 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
> [java] DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-2:job-11) Hosts's actual total CPU: 86432 and CPU after
> applying overprovisioning: 86432
> [java] DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-2:job-11) release cpu from host: 1, old used:
> 2000,reserved:
> 0, actual total: 86432, total with overprovisioning: 86432; new used:
> 1500,reserved:0; movedfromreserved: false,moveToReserveredfalse
> [java] DEBUG [cloud.capacity.CapacityManagerImpl]
> (Job-Executor-2:job-11) release mem from host: 1, old used:
> 2013265920,reserved: 0, total: 16668782592; new used:
> 1879048192,reserved:0; movedfromreserved: false,moveToReserveredfalse
> [java] INFO [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-2:job-11) Unable to contact resource.
> [java] com.cloud.exception.AgentUnavailableException: Resource
> [Host:1] is unreachable: Host 1: Unable to start instance due to Unable
> to
> start VM[DomainRouter|r-4-TEST] due to error in finalizeStart, not
> retrying
> [java] at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManag
> erImpl.java:847)
> [java] at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.
> java:472)
> [java] at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.start(Virtu
> alNetworkApplianceManagerImpl.java:2352)
> [java] at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.startVirtua
> lRouter(VirtualNetworkApplianceManagerImpl.java:1597)
> [java] at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.startRouter
> s(VirtualNetworkApplianceManagerImpl.java:1697)
> [java] at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.deployVirtu
> alRouterInGuestNetwork(VirtualNetworkApplianceManagerImpl.java:1675)
> [java] at
> com.cloud.network.element.VirtualRouterElement.prepare(VirtualRouterEle
> ment.java:205)
> [java] at
> com.cloud.network.NetworkManagerImpl.prepareElement(NetworkManagerImpl.
> java:2055)
> [java] at
> com.cloud.network.NetworkManagerImpl.prepareNic(NetworkManagerImpl.java
> :2171)
> [java] at
> com.cloud.network.NetworkManagerImpl.prepare(NetworkManagerImpl.java:21
> 12)
> [java] at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManag
> erImpl.java:752)
> [java] at
> com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.
> java:472)
> [java] at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.ja
> va:2929)
> [java] at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.ja
> va:2601)
> [java] at
> com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.ja
> va:2589)
> [java] at
> com.cloud.event.ActionEventCallback.intercept(ActionEventCallback.java:
> 36)
> [java] at
> com.cloud.api.commands.DeployVMCmd.execute(DeployVMCmd.java:330)
> [java] at
> com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:138)
> [java] at
> com.cloud.async.AsyncJobManagerImpl$1.run(AsyncJobManagerImpl.java:432)
> [java] at
> java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
> [java] at
> java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
> [java] at
> java.util.concurrent.FutureTask.run(FutureTask.java:166)
> [java] at
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.ja
> va:1110)
> [java] at
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.j
> ava:603)
> [java] at java.lang.Thread.run(Thread.java:679)
> [java] Caused by: com.cloud.utils.exception.ExecutionException:
> Unable
> to start VM[DomainRouter|r-4-TEST] due to error in finalizeStart, not
> retrying
> [java] at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManag
> erImpl.java:814)
> [java] ... 28 more
> [java] DEBUG [cloud.vm.VirtualMachineManagerImpl]
> (Job-Executor-2:job-11) Cleaning up resources for the vm
> VM[User|df931b4f-cf02-44fe-8147-63504fe8af2b] in Starting state
> [java] DEBUG [agent.transport.Request] (Job-Executor-2:job-11) Seq
> 1-908394509: Sending { Cmd , MgmtId: 130580009884, via: 1, Ver: v1,
> Flags:
> 100111, [{"StopCommand":{"isProxy":false,"vmName":"i-2-5-
> TEST","wait":0}}] }
>
>
> My management server is running in debug mode but agent is installed
> form
> binaries. I`m sure that rourter VM is running:
There is an undocumented parameter:
Need to set developer = true in configuration table, if you are running mgt
server with "ant debug" and hypervisor is kvm.
Usually, you can add
Mkdir setup/db/override
Cp setup/db/server-setup.xml setup/db/override/
Then add
<configuration>
<name>developer</name>
<value>true</value>
</configuration>
Into setup/db/override/server-setup.xml, in configuration section.
"Ant deploydb" will set developer = true.
>
> # virsh list
>
> Id Name State
> ----------------------------------------------------
> 1 v-2-TEST running
> 2 s-1-TEST running
> 16 r-4-TEST running
>
> I missed something during installation process? Or it is just a bug?
> --
> Pozdrawiam,
> Kamil Gardziejczyk
