[
https://issues.apache.org/jira/browse/CLOUDSTACK-7600?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Sheng Yang reassigned CLOUDSTACK-7600:
--------------------------------------
Assignee: Animesh Chaturvedi (was: Sheng Yang)
It's VM lifecycle issue. I am not sure it's due to host 2 disconnect or some
other issues.
Somebody familiar with VM lifecycle please take a look.
2014-09-21 14:38:20,596 DEBUG [c.c.n.r.VirtualNetworkApplianceManagerImpl]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Starting
router VM[DomainRouter|r-271-VM]
2014-09-21 14:38:20,605 DEBUG [c.c.u.d.T.Transaction]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Rolling back
the transaction: Time = 2 Name = Work-Job-Executor-65; called by
-TransactionLegacy.rollback:902-TransactionLegacy.removeUpTo:845-TransactionLegacy.close:669-Transaction.execute:41-VirtualMachineManagerImpl.changeToStartState:658-VirtualMachineManagerImpl.orchestrateStart:808-VirtualMachineManagerImpl.advanceStart:762-VirtualNetworkApplianceManagerImpl.start:2981-VirtualNetworkApplianceManagerImpl.startVirtualRouter:2055-VirtualNetworkApplianceManagerImpl.startRouters:2155-VirtualNetworkApplianceManagerImpl.deployVirtualRouterInGuestNetwork:2137-GeneratedMethodAccessor335.invoke:-1
2014-09-21 14:38:20,607 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Unable to
transition into Starting state due to Unable to transition to a new state from
Stopping via StartRequested
2014-09-21 14:38:20,608 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Determining
why we're unable to update the state to Starting for VM[DomainRouter|r-271-VM].
Retry=4
2014-09-21 14:38:20,609 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Unable to
find work for VM: VM[DomainRouter|r-271-VM] and state: Stopping
2014-09-21 14:38:20,610 DEBUG [c.c.u.d.T.Transaction]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Rolling back
the transaction: Time = 1 Name = Work-Job-Executor-65; called by
-TransactionLegacy.rollback:902-TransactionLegacy.removeUpTo:845-TransactionLegacy.close:669-Transaction.execute:41-VirtualMachineManagerImpl.changeToStartState:658-VirtualMachineManagerImpl.orchestrateStart:808-VirtualMachineManagerImpl.advanceStart:762-VirtualNetworkApplianceManagerImpl.start:2981-VirtualNetworkApplianceManagerImpl.startVirtualRouter:2055-VirtualNetworkApplianceManagerImpl.startRouters:2155-VirtualNetworkApplianceManagerImpl.deployVirtualRouterInGuestNetwork:2137-GeneratedMethodAccessor335.invoke:-1
2014-09-21 14:38:20,612 DEBUG [c.c.v.VirtualMachineManagerImpl]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Unable to
transition into Starting state due to Unable to transition to a new state from
Stopping via StartRequested
2014-09-21 14:38:20,729 ERROR [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Invocation
exception, caused by: com.cloud.exception.AgentUnavailableException: Resource
[Host:2] is unreachable: Host 2: Unable to start instance due to Unable to
change the state of VM[DomainRouter|r-271-VM]
2014-09-21 14:38:20,729 INFO [c.c.v.VmWorkJobHandlerProxy]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Rethrow
exception com.cloud.exception.AgentUnavailableException: Resource [Host:2] is
unreachable: Host 2: Unable to start instance due to Unable to change the state
of VM[DomainRouter|r-271-VM]
2014-09-21 14:38:20,729 DEBUG [c.c.v.VmWorkJobDispatcher]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954) Done with run of VM work
job: com.cloud.vm.VmWorkStart for VM 279, job origin: 1953
2014-09-21 14:38:20,729 ERROR [c.c.v.VmWorkJobDispatcher]
(Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954) Unable to complete
AsyncJobVO {id:1954, userId: 114, accountId: 115, instanceType: null,
instanceId: null, cmd: com.cloud.vm.VmWorkStart, cmdInfo:
rO0ABXNyABhjb20uY2xvdWQudm0uVm1Xb3JrU3RhcnR9cMGsvxz73gIAC0oABGRjSWRMAAZhdm9pZHN0ADBMY29tL2Nsb3VkL2RlcGxveS9EZXBsb3ltZW50UGxhbm5lciRFeGNsdWRlTGlzdDtMAAljbHVzdGVySWR0ABBMamF2YS9sYW5nL0xvbmc7TAAGaG9zdElkcQB-AAJMAAtqb3VybmFsTmFtZXQAEkxqYXZhL2xhbmcvU3RyaW5nO0wAEXBoeXNpY2FsTmV0d29ya0lkcQB-AAJMAAdwbGFubmVycQB-AANMAAVwb2RJZHEAfgACTAAGcG9vbElkcQB-AAJMAAlyYXdQYXJhbXN0AA9MamF2YS91dGlsL01hcDtMAA1yZXNlcnZhdGlvbklkcQB-AAN4cgATY29tLmNsb3VkLnZtLlZtV29ya5-ZtlbwJWdrAgAESgAJYWNjb3VudElkSgAGdXNlcklkSgAEdm1JZEwAC2hhbmRsZXJOYW1lcQB-AAN4cAAAAAAAAABzAAAAAAAAAHIAAAAAAAABF3QAGVZpcnR1YWxNYWNoaW5lTWFuYWdlckltcGwAAAAAAAAAAXBzcgAOamF2YS5sYW5nLkxvbmc7i-SQzI8j3wIAAUoABXZhbHVleHIAEGphdmEubGFuZy5OdW1iZXKGrJUdC5TgiwIAAHhwAAAAAAAAAAFzcQB-AAgAAAAAAAAAAnBwcHEAfgAKcHNyABFqYXZhLnV0aWwuSGFzaE1hcAUH2sHDFmDRAwACRgAKbG9hZEZhY3RvckkACXRocmVzaG9sZHhwP0AAAAAAAAx3CAAAABAAAAABdAAKVm1QYXNzd29yZHQAHHJPMEFCWFFBRG5OaGRtVmtYM0JoYzNOM2IzSmt4cA,
cmdVersion: 0, status: IN_PROGRESS, processStatus: 0, resultCode: 0, result:
null, initMsid: 16226561876200, completeMsid: null, lastUpdated: null,
lastPolled: null, created: Sun Sep 21 14:38:19 UTC 2014}, job origin:1953
com.cloud.exception.AgentUnavailableException: Resource [Host:2] is
unreachable: Host 2: Unable to start instance due to Unable to change the state
of VM[DomainRouter|r-271-VM]
at
com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:1090)
at
com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:4590)
at sun.reflect.GeneratedMethodAccessor379.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:601)
at
com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
at
com.cloud.vm.VirtualMachineManagerImpl.handleVmWorkJob(VirtualMachineManagerImpl.java:4746)
at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102)
at
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:516)
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
org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:473)
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:722)
> [Automation] VM Failed to Start due to ConcurrentOperationException - Unable
> to change the state of Virtual Router
> ------------------------------------------------------------------------------------------------------------------
>
> Key: CLOUDSTACK-7600
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-7600
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Automation, Test, Virtual Router
> Affects Versions: 4.5.0
> Reporter: Chandan Purushothama
> Assignee: Animesh Chaturvedi
> Priority: Critical
> Fix For: 4.5.0
>
> Attachments: management-server.zip
>
>
> *ConcurrentOperationException: Unable to change the state of VM*
> {noformat}
> 2014-09-21 14:38:20,648 ERROR [c.c.v.VirtualMachineManagerImpl]
> (Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Failed to
> start instance VM[User|i-115-279-VM]
> com.cloud.exception.ConcurrentOperationException: Unable to change the state
> of VM[DomainRouter|r-271-VM]
> at
> com.cloud.vm.VirtualMachineManagerImpl.changeToStartState(VirtualMachineManagerImpl.java:717)
> at
> com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:808)
> at
> com.cloud.vm.VirtualMachineManagerImpl.advanceStart(VirtualMachineManagerImpl.java:762)
> at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.start(VirtualNetworkApplianceManagerImpl.java:2981)
> at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.startVirtualRouter(VirtualNetworkApplianceManagerImpl.java:2055)
> at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.startRouters(VirtualNetworkApplianceManagerImpl.java:2155)
> at
> com.cloud.network.router.VirtualNetworkApplianceManagerImpl.deployVirtualRouterInGuestNetwork(VirtualNetworkApplianceManagerImpl.java:2137)
> at sun.reflect.GeneratedMethodAccessor335.invoke(Unknown Source)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:601)
> 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 $Proxy190.deployVirtualRouterInGuestNetwork(Unknown Source)
> at
> com.cloud.network.element.VirtualRouterElement.prepare(VirtualRouterElement.java:234)
> at
> org.apache.cloudstack.engine.orchestration.NetworkOrchestrator.prepareElement(NetworkOrchestrator.java:1239)
> at
> org.apache.cloudstack.engine.orchestration.NetworkOrchestrator.prepareNic(NetworkOrchestrator.java:1373)
> at
> org.apache.cloudstack.engine.orchestration.NetworkOrchestrator.prepare(NetworkOrchestrator.java:1309)
> at
> com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:970)
> at
> com.cloud.vm.VirtualMachineManagerImpl.orchestrateStart(VirtualMachineManagerImpl.java:4590)
> at sun.reflect.GeneratedMethodAccessor379.invoke(Unknown Source)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:601)
> at
> com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
> at
> com.cloud.vm.VirtualMachineManagerImpl.handleVmWorkJob(VirtualMachineManagerImpl.java:4746)
> at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:102)
> at
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:516)
> 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
> org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:473)
> 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:722)
> 2014-09-21 14:38:20,651 DEBUG [c.c.v.VirtualMachineManagerImpl]
> (Work-Job-Executor-65:ctx-cfa1f316 job-1953/job-1954 ctx-6fd40296) Cleaning
> up resources for the vm VM[User|i-115-279-VM] in Starting state
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
