Return-Path: X-Original-To: apmail-cloudstack-issues-archive@www.apache.org Delivered-To: apmail-cloudstack-issues-archive@www.apache.org Received: from mail.apache.org (hermes.apache.org [140.211.11.3]) by minotaur.apache.org (Postfix) with SMTP id 3DD1E11169 for ; Thu, 10 Jul 2014 11:56:06 +0000 (UTC) Received: (qmail 7326 invoked by uid 500); 10 Jul 2014 11:56:06 -0000 Delivered-To: apmail-cloudstack-issues-archive@cloudstack.apache.org Received: (qmail 7296 invoked by uid 500); 10 Jul 2014 11:56:06 -0000 Mailing-List: contact issues-help@cloudstack.apache.org; run by ezmlm Precedence: bulk List-Help: List-Unsubscribe: List-Post: List-Id: Reply-To: dev@cloudstack.apache.org Delivered-To: mailing list issues@cloudstack.apache.org Received: (qmail 7285 invoked by uid 500); 10 Jul 2014 11:56:06 -0000 Delivered-To: apmail-incubator-cloudstack-issues@incubator.apache.org Received: (qmail 7282 invoked by uid 99); 10 Jul 2014 11:56:06 -0000 Received: from arcas.apache.org (HELO arcas.apache.org) (140.211.11.28) by apache.org (qpsmtpd/0.29) with ESMTP; Thu, 10 Jul 2014 11:56:06 +0000 Date: Thu, 10 Jul 2014 11:56:05 +0000 (UTC) From: "sadhu suresh (JIRA)" To: cloudstack-issues@incubator.apache.org Message-ID: In-Reply-To: References: Subject: [jira] [Commented] (CLOUDSTACK-7079) 'update:Exception:Invalid cluster session detected' error thrown after MS restart MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-JIRA-FingerPrint: 30527f35849b9dde25b450d4833f0394 [ 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)