[
https://issues.apache.org/jira/browse/CLOUDSTACK-6913?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14079126#comment-14079126
]
le cong duan commented on CLOUDSTACK-6913:
------------------------------------------
I have the same problem. After delete template,when I click "Migartion" icon of
VM but it not affect. I try enable debug mode on browser (ex: Chrome) then I
see that Server return error code 530 for browser client. Beside I also can't
start VM after stop VM. I use KVM for Hypervisor
The following is log when I click "Migration" incon on web interface:
2014-07-30 16:51:12,359 DEBUG [c.c.s.ManagementServerImpl]
(catalina-exec-23:ctx-21edf4a7 ctx-22374871) Searching for all hosts in cluster
1 for migrating VM VM[User|vm1-ha]
2014-07-30 16:51:12,366 DEBUG [c.c.a.m.a.i.FirstFitAllocator]
(catalina-exec-23:ctx-21edf4a7 ctx-22374871) Looking for hosts in dc: 1 pod:1
cluster:1
2014-07-30 16:51:12,366 ERROR [c.c.a.ApiServer] (catalina-exec-23:ctx-21edf4a7
ctx-22374871) unhandled exception executing api command: findHostsForMigration
java.lang.NullPointerException
at
com.cloud.agent.manager.allocator.impl.FirstFitAllocator.allocateTo(FirstFitAllocator.java:135)
at
com.cloud.server.ManagementServerImpl.listHostsForMigrationOfVM(ManagementServerImpl.java:1198)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:616)
at
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at $Proxy193.listHostsForMigrationOfVM(Unknown Source)
at
org.apache.cloudstack.api.command.admin.host.FindHostsForMigrationCmd.execute(FindHostsForMigrationCmd.java:75)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:161)
at com.cloud.api.ApiServer.queueCommand(ApiServer.java:531)
at com.cloud.api.ApiServer.handleRequest(ApiServer.java:374)
at com.cloud.api.ApiServlet.processRequestInContext(ApiServlet.java:323)
at com.cloud.api.ApiServlet.access$000(ApiServlet.java:53)
at com.cloud.api.ApiServlet$1.run(ApiServlet.java:115)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
at com.cloud.api.ApiServlet.processRequest(ApiServlet.java:112)
at com.cloud.api.ApiServlet.doGet(ApiServlet.java:74)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
at
org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
at
org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at
org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
at
org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
at
org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
at
org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
at
org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:555)
at
org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at
org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:298)
at
org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:889)
at
org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:721)
at
org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:2274)
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)
2014-07-30 16:51:12,369 DEBUG [c.c.a.ApiServlet] (catalina-exec-23:ctx-21edf4a7
ctx-22374871) ===END=== 10.0.1.247 -- GET
command=findHostsForMigration&VirtualMachineId=9c9b8e61-cc08-4667-a2a1-793e4c535144&response=json&sessionkey=sbv6j2TiSGR%2BI8fJHfJtESi8xB4%3D&_=1406713877717
The following is log on management server when i start vm after stop it :
2014-07-30 16:56:30,780 DEBUG [c.c.c.CapacityManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Host has enough CPU and RAM available
2014-07-30 16:56:30,781 DEBUG [c.c.c.CapacityManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) STATS: Can alloc CPU from host: 14,
used: 30000, reserved: 500, actual total: 17064, total with overprovisioning:
34128; requested cpu:500,alloc_from_last_host?:true ,considerReservedCapacity?:
true
2014-07-30 16:56:30,781 DEBUG [c.c.c.CapacityManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) STATS: Can alloc MEM from host: 14,
used: 16777216000, reserved: 536870912, total: 25274761216; requested mem:
536870912,alloc_from_last_host?:true ,considerReservedCapacity?: true
2014-07-30 16:56:30,782 DEBUG [c.c.c.CapacityManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Host: 14 has cpu capability (cpu:8,
speed:2133) to support requested CPU: 1 and requested speed: 500
2014-07-30 16:56:30,783 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) The last host of this VM is UP and
has enough capacity
2014-07-30 16:56:30,783 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Now checking for suitable pools
under zone: 1, pod: 1, cluster: 1
2014-07-30 16:56:30,786 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Checking suitable pools for volume
(Id, Type): (12,ROOT)
2014-07-30 16:56:30,786 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Volume has pool already allocated,
checking if pool can be reused, poolId: 2
2014-07-30 16:56:30,788 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Planner need not allocate a pool for
this volume since its READY
2014-07-30 16:56:30,788 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Trying to find a potenial host and
associated storage pools from the suitable host/pool lists for this VM
2014-07-30 16:56:30,789 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Checking if host: 14 can access any
suitable storage pool for volume: ROOT
2014-07-30 16:56:30,791 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Host: 14 can access pool: 2
2014-07-30 16:56:30,793 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Found a potential host id: 14 name:
host01-test and associated storage pools for this VM
2014-07-30 16:56:30,793 DEBUG [c.c.d.DeploymentPlanningManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) 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(14)-Storage()]
2014-07-30 16:56:30,888 DEBUG [c.c.c.CapacityManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) VM state transitted from :Stopped to
Starting with event: StartRequestedvm's original host id: 14 new host id: null
host id before state transition: null
2014-07-30 16:56:30,888 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Successfully transitioned to start
state for VM[User|vm1-ha] reservation id = 093ef66b-19f3-4bb0-9c2d-7d428428c4ae
2014-07-30 16:56:30,958 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Trying to deploy VM, vm has dcId: 1
and podId: 1
2014-07-30 16:56:30,958 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) advanceStart: DeploymentPlan is
provided, using dcId:1, podId: 1, clusterId: 1, hostId: 14, poolId: null
2014-07-30 16:56:30,958 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Deploy avoids pods: null, clusters:
null, hosts: null
2014-07-30 16:56:31,115 DEBUG [c.c.c.CapacityManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) VM state transitted from :Starting
to Stopped with event: OperationFailedvm's original host id: 14 new host id:
null host id before state transition: null
2014-07-30 16:56:31,264 DEBUG [c.c.c.CapacityManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) VM state transitted from :Stopped to
Starting with event: StartRequestedvm's original host id: 14 new host id: null
host id before state transition: null
2014-07-30 16:56:31,264 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Successfully transitioned to start
state for VM[User|vm1-ha] reservation id = 8bb64c6f-6cf9-4cd1-865a-78df0c14a202
2014-07-30 16:56:31,266 DEBUG [c.c.s.StatsCollector]
(StatsCollector-2:ctx-94117197) VmStatsCollector is running...
2014-07-30 16:56:31,393 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Trying to deploy VM, vm has dcId: 1
and podId: 1
2014-07-30 16:56:31,393 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) Deploy avoids pods: null, clusters:
null, hosts: null
2014-07-30 16:56:31,394 DEBUG [c.c.a.t.Request] (StatsCollector-2:ctx-94117197)
Seq 1-2122907742: Received: { Ans: , MgmtId: 161332613502, via: 1, Ver: v1,
Flags: 10, { GetVmStatsAnswer } }
2014-07-30 16:56:31,429 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl]
(RouterStatusMonitor-1:ctx-94f66631) Found 0 routers to update status.
2014-07-30 16:56:31,432 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl]
(RouterStatusMonitor-1:ctx-94f66631) Found 4 networks to update RvR status.
2014-07-30 16:56:31,473 DEBUG [c.c.a.t.Request] (StatsCollector-2:ctx-94117197)
Seq 7-1067057243: Received: { Ans: , MgmtId: 161332613502, via: 7, Ver: v1,
Flags: 10, { GetVmStatsAnswer } }
2014-07-30 16:56:31,541 DEBUG [c.c.c.CapacityManagerImpl]
(Job-Executor-2:ctx-e4af58dc ctx-67885b23) VM state transitted from :Starting
to Stopped with event: OperationFailedvm's original host id: 14 new host id:
null host id before state transition: null
2014-07-30 16:56:31,718 ERROR [c.c.a.ApiAsyncJobDispatcher]
(Job-Executor-2:ctx-e4af58dc) Unexpected exception while executing
org.apache.cloudstack.api.command.user.vm.StartVMCmd
java.lang.NullPointerException
at
com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:858)
at
com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:761)
at
com.cloud.vm.VirtualMachineManagerImpl.start(VirtualMachineManagerImpl.java:601)
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:207)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:3581)
at
com.cloud.vm.UserVmManagerImpl.startVirtualMachine(UserVmManagerImpl.java:2043)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:616)
at
org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at
com.cloud.event.ActionEventInterceptor.invoke(ActionEventInterceptor.java:50)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161)
at
org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
at
org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at
org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at $Proxy169.startVirtualMachine(Unknown Source)
at
org.apache.cloudstack.api.command.user.vm.StartVMCmd.execute(StartVMCmd.java:121)
at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:161)
at
com.cloud.api.ApiAsyncJobDispatcher.runJobInContext(ApiAsyncJobDispatcher.java:109)
at
com.cloud.api.ApiAsyncJobDispatcher$1.run(ApiAsyncJobDispatcher.java:66)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
at
com.cloud.api.ApiAsyncJobDispatcher.runJob(ApiAsyncJobDispatcher.java:63)
at
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:509)
at
org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
at
org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
at
org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
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.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:679)
2014-07-30 16:56:31,720 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl]
(Job-Executor-2:ctx-e4af58dc) Complete async job-939, jobStatus: FAILED,
resultCode: 530, result:
org.apache.cloudstack.api.response.ExceptionResponse/null/{"uuidList":[],"errorcode":530}
> VM could not perform live migrate afte the template deleted.
> ------------------------------------------------------------
>
> Key: CLOUDSTACK-6913
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-6913
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Hypervisor Controller, Management Server
> Affects Versions: 4.3.0
> Environment: Cloudstack4.3+Xenserver6.2sp1
> Reporter: Guangsheng Xu
> Assignee: Sanjay Tripathi
> Original Estimate: 672h
> Remaining Estimate: 672h
>
> I use a template to launch a Instance, after the Instance up and running, I
> can migrate the VM to a nother host inside the Cluster via Cloudstack Web by
> clicking 'Migrate'on this VM. But after I delete the template which I used to
> create this VM. Then a few minutes latter, there is no response when I click
> 'Migrate' on this VM.
> Catalina log shows: GET command=findHostsForMigration&VirtualMachineId 530
> null
--
This message was sent by Atlassian JIRA
(v6.2#6252)
