]
Radoslav Husar updated WFLY-4941:
---------------------------------
Summary: WFLYCLEJBINF0005: Failed to cancel[schedule] expiration/passivation of bean
UnknownSessionID on primary owner.: ExecutionException: TimeoutException: timeout sending
message (was: WFLYCLEJBINF0005: Failed to cancel expiration/passivation of bean
UnknownSessionID on primary owner.: ExecutionException: TimeoutException: timeout sending
message)
WFLYCLEJBINF0005: Failed to cancel[schedule] expiration/passivation
of bean UnknownSessionID on primary owner.: ExecutionException: TimeoutException: timeout
sending message
-----------------------------------------------------------------------------------------------------------------------------------------------------------------------------
Key: WFLY-4941
URL:
https://issues.jboss.org/browse/WFLY-4941
Project: WildFly
Issue Type: Bug
Components: Clustering
Affects Versions: 10.0.0.Alpha6, 10.0.0.Beta1
Reporter: Michal Vinkler
Assignee: Radoslav Husar
Seen in our failover tests - this warning message is present in each server log in each
test we have run so far.
When each server in cluster is restarting after failover, other servers get CacheTopology
change (the starting server is added) and the servers log this warning message many
times:
(for http session tests, the error identificator is WFLYCL*WEB*INF0005 rather than
WFLYCL*EJB*INF0005)
{code}
[JBossINF] [0m[0m11:32:27,681 INFO [org.infinispan.CLUSTER] (remote-thread--p5-t12)
ISPN000336: Finished cluster-wide rebalance for cache dist, topology id = 10
[JBossINF] [0m[0m11:32:27,809 INFO [org.infinispan.CLUSTER] (remote-thread--p6-t2)
ISPN000310: Starting cluster-wide rebalance for cache
clusterbench-ee7.ear/clusterbench-ee7-ejb.jar, topology CacheTopology{id=10,
rebalanceId=5, currentCH=DefaultConsistentHash{ns=80, owners = (3)[perf19: 26+28, perf20:
27+26, perf21: 27+26]}, pendingCH=DefaultConsistentHash{ns=80, owners = (4)[perf19: 20+20,
perf20: 20+20, perf21: 20+20, perf18: 20+20]}, unionCH=null, actualMembers=[perf19,
perf20, perf21, perf18]}
[JBossINF] [0m[0m11:32:28,347 INFO [org.infinispan.CLUSTER] (remote-thread--p6-t6)
ISPN000336: Finished cluster-wide rebalance for cache
clusterbench-ee7.ear/clusterbench-ee7-ejb.jar, topology id = 10
[JBossINF] [0m[33m11:32:28,624 WARN [org.wildfly.clustering.ejb.infinispan] (EJB default
- 4) WFLYCLEJBINF0005: Failed to cancel expiration/passivation of bean UnknownSessionID
[6855655451695655554868505252524957485470656956657048536848556569] on primary owner.:
java.util.concurrent.ExecutionException: java.util.concurrent.TimeoutException: timeout
sending message to perf18
[JBossINF] at org.jgroups.blocks.UnicastRequest.getValue(UnicastRequest.java:203)
[JBossINF] at org.jgroups.blocks.UnicastRequest.get(UnicastRequest.java:212)
[JBossINF] at
org.wildfly.clustering.server.dispatcher.ChannelCommandDispatcher.executeOnNode(ChannelCommandDispatcher.java:151)
[JBossINF] at
org.wildfly.clustering.ejb.infinispan.InfinispanBeanManager$6.call(InfinispanBeanManager.java:237)
[JBossINF] at
org.wildfly.clustering.ejb.infinispan.InfinispanBeanManager$6.call(InfinispanBeanManager.java:232)
[JBossINF] at
org.wildfly.clustering.ee.infinispan.RetryingInvoker.invoke(RetryingInvoker.java:70)
[JBossINF] at
org.wildfly.clustering.ejb.infinispan.InfinispanBeanManager.executeOnPrimaryOwner(InfinispanBeanManager.java:240)
[JBossINF] at
org.wildfly.clustering.ejb.infinispan.InfinispanBeanManager.cancel(InfinispanBeanManager.java:217)
[JBossINF] at
org.wildfly.clustering.ejb.infinispan.InfinispanBeanManager.findBean(InfinispanBeanManager.java:267)
[JBossINF] at
org.jboss.as.ejb3.cache.distributable.DistributableCache.get(DistributableCache.java:115)
[JBossINF] at
org.jboss.as.ejb3.component.stateful.StatefulComponentInstanceInterceptor.processInvocation(StatefulComponentInstanceInterceptor.java:58)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:275)
[JBossINF] at org.jboss.as.ejb3.tx.CMTTxInterceptor.required(CMTTxInterceptor.java:327)
[JBossINF] at
org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:239)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ejb3.remote.EJBRemoteTransactionPropagatingInterceptor.processInvocation(EJBRemoteTransactionPropagatingInterceptor.java:79)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:43)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:100)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ejb3.deployment.processors.EjbSuspendInterceptor.processInvocation(EjbSuspendInterceptor.java:53)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:66)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:54)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:64)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:356)
[JBossINF] at
org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:635)
[JBossINF] at
org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:61)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:356)
[JBossINF] at
org.jboss.invocation.PrivilegedWithCombinerInterceptor.processInvocation(PrivilegedWithCombinerInterceptor.java:80)
[JBossINF] at
org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:340)
[JBossINF] at
org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
[JBossINF] at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:195)
[JBossINF] at
org.jboss.as.ejb3.remote.protocol.versionone.MethodInvocationMessageHandler.invokeMethod(MethodInvocationMessageHandler.java:331)
[JBossINF] at
org.jboss.as.ejb3.remote.protocol.versionone.MethodInvocationMessageHandler.access$100(MethodInvocationMessageHandler.java:69)
[JBossINF] at
org.jboss.as.ejb3.remote.protocol.versionone.MethodInvocationMessageHandler$1.run(MethodInvocationMessageHandler.java:202)
[JBossINF] at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[JBossINF] at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[JBossINF] at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[JBossINF] at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[JBossINF] at java.lang.Thread.run(Thread.java:745)
[JBossINF] at org.jboss.threads.JBossThread.run(JBossThread.java:320)
[JBossINF] Caused by: java.util.concurrent.TimeoutException: timeout sending message to
perf18
[JBossINF] ... 54 more
[JBossINF]
{code}
Server log:
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/eap-7x-failover-ejb-e...
Client log:
http://jenkins.mw.lab.eng.bos.redhat.com/hudson/job/eap-7x-failover-ejb-e...