[
https://issues.apache.org/jira/browse/CLOUDSTACK-7079?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=14057397#comment-14057397
]
sadhu suresh commented on CLOUDSTACK-7079:
------------------------------------------
current.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:744)
Caused by: com.cloud.cluster.ClusterInvalidSessionException: runid
1405011953267 is no longer valid
at
com.cloud.cluster.dao.ManagementServerHostDaoImpl.update(ManagementServerHostDaoImpl.java:148)
... 25 more
2014-07-10 22:38:49,146 ERROR [c.c.c.ClusterManagerImpl]
(Cluster-Heartbeat-1:ctx-08d70db3) Unexpected exception in cluster heartbeat
java.lang.RuntimeException: update:Exception:Invalid cluster session detected
at
com.cloud.cluster.dao.ManagementServerHostDaoImpl.update(ManagementServerHostDaoImpl.java:158)
at sun.reflect.GeneratedMethodAccessor88.invoke(Unknown Source)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
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.utils.db.TransactionContextInterceptor.invoke(TransactionContextInterceptor.java:34)
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 com.sun.proxy.$Proxy150.update(Unknown Source)
at
com.cloud.cluster.ClusterManagerImpl$4.runInContext(ClusterManagerImpl.java:545)
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.runAndReset(FutureTask.java:304)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
at
java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
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)
Caused by: com.cloud.utils.exception.CloudRuntimeException: Invalid cluster
session detected
at
com.cloud.cluster.dao.ManagementServerHostDaoImpl.update(ManagementServerHostDaoImpl.java:148)
... 25 more
Caused by: com.cloud.cluster.ClusterInvalidSessionException: runid
1405011953267 is no longer valid
at
com.cloud.cluster.dao.ManagementServerHostDaoImpl.update(ManagementServerHostDaoImpl.java:148)
... 25 more
2014-07-10 22:38:49,214 DEBUG [c.c.s.StatsCollector]
(StatsCollector-4:ctx-e14f2f32) AutoScaling Monitor is running...
2014-07-10 22:38:49,242 DEBUG [c.c.s.StatsCollector]
(StatsCollector-2:ctx-42b7b51e) VmStatsCollector is running...
2014-07-10 22:38:49,511 DEBUG [c.c.s.StatsCollector]
(StatsCollector-1:ctx-b3053f67) HostStatsCollector is running...
2014-07-10 22:38:49,574 WARN [o.a.c.f.j.AsyncJobExecutionContext]
(StatsCollector-1:ctx-b3053f67) Job is executed without a context, setup psudo
job for the executing thread
2014-07-10 22:38:49,593 DEBUG [c.c.a.m.DirectAgentAttache]
(DirectAgent-2:ctx-73d916e4) Seq 1-6114199444108869639: Executing request
2014-07-10 22:38:49,700 DEBUG [c.c.a.m.DirectAgentAttache]
(DirectAgent-2:ctx-73d916e4) Seq 1-6114199444108869639: Response Received:
2014-07-10 22:38:49,703 DEBUG [c.c.a.t.Request] (StatsCollector-1:ctx-b3053f67)
Seq 1-6114199444108869639: Received: { Ans: , MgmtId: 7175246184473, via: 1,
Ver: v1, Flags: 10, { GetHostStatsAnswer } }
2014-07-10 22:38:49,732 DEBUG [c.c.s.StatsCollector]
(StatsCollector-3:ctx-a46af4f6) StorageCollector is running...
2014-07-10 22:38:49,743 DEBUG [c.c.s.StatsCollector]
(StatsCollector-3:ctx-a46af4f6) There is no secondary storage VM for secondary
storage host secondary
2014-07-10 22:38:49,750 WARN [o.a.c.f.j.AsyncJobExecutionContext]
(StatsCollector-3:ctx-a46af4f6) Job is executed without a context, setup psudo
job for the executing thread
2014-07-10 22:38:49,757 DEBUG [c.c.a.m.DirectAgentAttache]
(DirectAgent-4:ctx-532575bb) Seq 1-6114199444108869640: Executing request
2014-07-10 22:38:49,840 DEBUG [c.c.h.v.r.VmwareResource]
(DirectAgent-4:ctx-532575bb 10.147.40.9, job-32, cmd: GetStorageStatsCommand)
Datastore summary info, storageId: 15ba41e5-da61-3ab3-a90f-85dbf0863adf,
localPath: /export/home/sadhu/4.5p, poolType: NetworkFilesystem, capacity:
5902284800000, free: 3753235046400, used: 2149049753600
2014-07-10 22:38:49,842 DEBUG [c.c.a.m.DirectAgentAttache]
(DirectAgent-4:ctx-532575bb) Seq 1-6114199444108869640: Response Received:
2014-07-10 22:38:49,842 DEBUG [c.c.a.t.Request] (StatsCollector-3:ctx-a46af4f6)
Seq 1-6114199444108869640: Received: { Ans: , MgmtId: 7175246184473, via: 1,
Ver: v1, Flags: 10, { GetStorageStatsAnswer } }
^C
> 'update:Exception:Invalid cluster session detected' error thrown after MS
> restart
> ---------------------------------------------------------------------------------
>
> Key: CLOUDSTACK-7079
> URL: https://issues.apache.org/jira/browse/CLOUDSTACK-7079
> Project: CloudStack
> Issue Type: Bug
> Security Level: Public(Anyone can view this level - this is the
> default.)
> Components: Management Server
> Affects Versions: 4.5.0
> Reporter: Likitha Shetty
> Assignee: Santhosh Kumar Edukulla
> Priority: Critical
> Fix For: 4.5.0
>
>
> After an MS restart the following error is observed in the logs-
> {noformat}
> 2014-07-08 21:27:54,548 WARN [c.c.c.d.ManagementServerHostDaoImpl]
> (Cluster-Heartbeat-1:ctx-d357302f) update:Exception:Invalid cluster session
> detected
> com.cloud.utils.exception.CloudRuntimeException: Invalid cluster session
> detected
> at
> com.cloud.cluster.dao.ManagementServerHostDaoImpl.update(ManagementServerHostDaoImpl.java:147)
> 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:606)
> 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.utils.db.TransactionContextInterceptor.invoke(TransactionContextInterceptor.java:34)
> 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 com.sun.proxy.$Proxy160.update(Unknown Source)
> at
> com.cloud.cluster.ClusterManagerImpl$4.runInContext(ClusterManagerImpl.java:545)
> 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.runAndReset(FutureTask.java:304)
> at
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:178)
> at
> java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
> 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)
> Caused by: com.cloud.cluster.ClusterInvalidSessionException: runid
> 1404835017785 is no longer valid
> at
> com.cloud.cluster.dao.ManagementServerHostDaoImpl.update(ManagementServerHostDaoImpl.java:147)
> ... 26 more
> {noformat}
> The reason for the above error is that after an MS restart, Management
> Server's runId is not updated. The update fails with the below -
> {noformat}
> 2014-07-08 21:27:52,995 INFO [c.c.c.ClusterManagerImpl] (main:null)
> Management server 55528590152550 is being started
> 2014-07-08 21:27:52,996 DEBUG [c.c.u.d.T.Transaction] (main:null) Rolling
> back the transaction: Time = 3 Name = tx-5; called by
> -TransactionLegacy.rollback:905-TransactionLegacy.removeUpTo:848-TransactionLegacy.close:672-TransactionContextInterceptor.invoke:36-ReflectiveMethodInvocation.proceed:161-ExposeInvocationInterceptor.invoke:91-ReflectiveMethodInvocation.proceed:172-JdkDynamicAopProxy.invoke:204-$Proxy160.update:-1-ClusterManagerImpl$6.doInTransaction:970-ClusterManagerImpl$6.doInTransaction:940-Transaction$2.doInTransaction:49
> 2014-07-08 21:27:53,026 WARN [c.c.u.d.T.Transaction] (main:null) txn: Commit
> called when it is not a transaction:
> -Transaction.execute:38-Transaction.execute:46-ClusterManagerImpl.start:940-CloudStackExtendedLifeCycle$1.with:75-CloudStackExtendedLifeCycle.with:153-CloudStackExtendedLifeCycle.startBeans:72-CloudStackExtendedLifeCycleStart.run:46-DefaultModuleDefinitionSet$1.with:105-DefaultModuleDefinitionSet.withModule:245-DefaultModuleDefinitionSet.withModule:250-DefaultModuleDefinitionSet.withModule:250-DefaultModuleDefinitionSet.withModule:233
> {noformat}
--
This message was sent by Atlassian JIRA
(v6.2#6252)
