[infinispan-issues] [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
Cyrille Charron (JIRA)
jira-events at lists.jboss.org
Thu Dec 16 08:42:18 EST 2010
[ https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=12570681#comment-12570681 ]
Cyrille Charron commented on ISPN-832:
--------------------------------------
That took me some time, but i managed to download, patch and install hibernate 3.6 via git.
I really had to do :
git config --global http.proxy http://myProxy:80
git clone https://github.com/hibernate/hibernate-core.git
cd hibernate-core
git checkout 3.6
mvn -Djdk16_home=C:\PROGRA~1\Java\jdk1.6.0_22 -Dmaven.test.skip.exec=true clean install -pl core,testing,cache-infinispan
and i had to copy manually the 3 jar files hibernate-infinispan-3.6.1-SNAPSHOT.jar, hibernate-testing-3.6.1-SNAPSHOT.jar and hibernate-core-3.6.1-SNAPSHOT.jar into my local .m2 repository since only *.jdocbook-style, *.pom and *-sources.jar were copied by maven ... a mystery !
As before with my 3.6.1-SNAPSHOT try, i verified into hibernate-infinispan-3.6.1-SNAPSHOT.jar file that your patch code was present into the byte code. I saw all the same patch code except for jcipannotations related codes which were absents in your pom.xml and BaseRegion.java files.
The result is the same as before, there is a TimeoutException when i run 2 nodes and i activate the query cache.
Here is the stdout :
14:27:58,011 InfiniSpanTest is starting its node 0/2
16 déc. 2010 14:28:00 org.apache.openejb.cdi.CdiBuilder <init>
INFO: Created new singletonService org.apache.openejb.cdi.ThreadSingletonServiceImpl at 162dbb6
16 déc. 2010 14:28:00 org.apache.openejb.cdi.CdiBuilder <init>
INFO: succeeded in installing singleton service
Apache OpenEJB 3.2-SNAPSHOT build: 20101203-01:57
http://openejb.apache.org/
16-12 14:28:00,660 INFO Log4jLogStream -openejb.home = D:\work35\InfiniSpanTest
16-12 14:28:00,691 INFO Log4jLogStream -openejb.base = D:\work35\InfiniSpanTest
16-12 14:28:03,637 INFO Log4jLogStream -Configuring Service(id=Default Security Service, type=SecurityService, provider-id=Default Security Service)
16-12 14:28:03,637 INFO Log4jLogStream -Configuring Service(id=Default Transaction Manager, type=TransactionManager, provider-id=Default Transaction Manager)
16-12 14:28:03,637 INFO Log4jLogStream -Configuring Service(id=jpacacheDS, type=Resource, provider-id=Default JDBC Database)
16-12 14:28:03,653 INFO Log4jLogStream -Configuring Service(id=My Stateless Container, type=Container, provider-id=Default Stateless Container)
16-12 14:28:04,011 INFO Log4jLogStream -Found EjbModule in classpath: D:\work35\InfiniSpanTest\target\test-classes
16-12 14:28:04,214 INFO Log4jLogStream -Found EjbModule in classpath: D:\work35\InfiniSpanTest\target\classes
16-12 14:28:04,401 INFO Log4jLogStream -Beginning load: D:\work35\InfiniSpanTest\target\test-classes
16-12 14:28:04,619 INFO Log4jLogStream -Beginning load: D:\work35\InfiniSpanTest\target\classes
16-12 14:28:06,536 INFO Log4jLogStream -Configuring enterprise application: classpath.ear
16-12 14:28:07,939 INFO Log4jLogStream -Configuring PersistenceUnit(name=jpacache, provider=org.hibernate.ejb.HibernatePersistence)
16-12 14:28:07,954 INFO Log4jLogStream -Auto-creating a Resource with id 'jpacacheDSNonJta' of type 'DataSource for 'jpacache'.
16-12 14:28:07,954 INFO Log4jLogStream -Configuring Service(id=jpacacheDSNonJta, type=Resource, provider-id=jpacacheDS)
16-12 14:28:07,954 INFO Log4jLogStream -Adjusting PersistenceUnit jpacache <non-jta-data-source> to Resource ID 'jpacacheDSNonJta' from 'null'
16-12 14:28:08,064 INFO Log4jLogStream -Enterprise application "classpath.ear" loaded.
16-12 14:28:08,064 INFO Log4jLogStream -Creating TransactionManager(id=Default Transaction Manager)
16-12 14:28:08,718 INFO Log4jLogStream -Creating SecurityService(id=Default Security Service)
16-12 14:28:09,092 INFO Log4jLogStream -Creating Resource(id=jpacacheDS)
16-12 14:28:09,217 INFO Log4jLogStream -Creating Resource(id=jpacacheDSNonJta)
16-12 14:28:09,248 INFO Log4jLogStream -Creating Container(id=My Stateless Container)
16-12 14:28:09,404 INFO Log4jLogStream -Assembling app: classpath.ear
16-12 14:28:17,213 INFO JGroupsTransport -Starting JGroups Channel
-------------------------------------------------------------------
GMS: address=W20349-46606, cluster=infinispan-hibernate-cluster, physical address=10.8.245.147:35753
-------------------------------------------------------------------
16-12 14:28:21,405 INFO AbstractLogImpl -Received new cluster view: [W20349-46606|0] [W20349-46606]
16-12 14:28:21,405 INFO AbstractLogImpl -Cache local address is W20349-46606, physical addresses are [10.8.245.147:35753]
16-12 14:28:21,405 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:21,452 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:22,091 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:22,138 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:22,169 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:22,231 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:22,278 INFO AbstractLogImpl -Received new cluster view: [W20349-46606|1] [W20349-46606, W20349-29522]
16-12 14:28:22,730 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:39,220 ERROR InvocationContextInterceptor -Execution error:
org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-46606,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.container.EntryFactoryImpl.acquireLock(EntryFactoryImpl.java:228)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:132)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeededAndUpdateStats(CacheLoaderInterceptor.java:217)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitGetKeyValueCommand(CacheLoaderInterceptor.java:89)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitGetKeyValueCommand(MarshalledValueInterceptor.java:168)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistReadAndInvokeNext(TxInterceptor.java:169)
at org.infinispan.interceptors.TxInterceptor.visitGetKeyValueCommand(TxInterceptor.java:160)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:95)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:51)
at org.infinispan.remoting.InboundInvocationHandlerImpl.handle(InboundInvocationHandlerImpl.java:94)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.executeCommand(CommandAwareRpcDispatcher.java:177)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.handle(CommandAwareRpcDispatcher.java:149)
at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:578)
at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:489)
at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:365)
at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.up(MessageDispatcher.java:771)
at org.jgroups.JChannel.up(JChannel.java:1465)
at org.jgroups.stack.ProtocolStack.up(ProtocolStack.java:954)
at org.jgroups.protocols.pbcast.FLUSH.up(FLUSH.java:478)
at org.jgroups.protocols.pbcast.STREAMING_STATE_TRANSFER.up(STREAMING_STATE_TRANSFER.java:265)
at org.jgroups.protocols.FRAG2.up(FRAG2.java:190)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.pbcast.GMS.up(GMS.java:888)
at org.jgroups.protocols.pbcast.STABLE.up(STABLE.java:234)
at org.jgroups.protocols.UNICAST.up(UNICAST.java:310)
at org.jgroups.protocols.pbcast.NAKACK.handleMessage(NAKACK.java:836)
at org.jgroups.protocols.pbcast.NAKACK.up(NAKACK.java:671)
at org.jgroups.protocols.VERIFY_SUSPECT.up(VERIFY_SUSPECT.java:132)
at org.jgroups.protocols.FD_ALL.up(FD_ALL.java:169)
at org.jgroups.protocols.FD_SOCK.up(FD_SOCK.java:269)
at org.jgroups.protocols.MERGE2.up(MERGE2.java:210)
at org.jgroups.protocols.Discovery.up(Discovery.java:292)
at org.jgroups.protocols.PING.up(PING.java:67)
at org.jgroups.protocols.TP.passMessageUp(TP.java:1093)
at org.jgroups.protocols.TP.access$100(TP.java:56)
at org.jgroups.protocols.TP$IncomingPacket.handleMyMessage(TP.java:1633)
at org.jgroups.protocols.TP$IncomingPacket.run(TP.java:1615)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
16-12 14:28:39,298 ERROR InvocationContextInterceptor -Execution error:
org.infinispan.loaders.CacheLoaderException: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-29522,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.loaders.cluster.ClusterCacheLoader.doRemoteCall(ClusterCacheLoader.java:102)
at org.infinispan.loaders.cluster.ClusterCacheLoader.load(ClusterCacheLoader.java:54)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:148)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitPutKeyValueCommand(CacheLoaderInterceptor.java:79)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitPutKeyValueCommand(MarshalledValueInterceptor.java:125)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistWriteAndInvokeNext(TxInterceptor.java:182)
at org.infinispan.interceptors.TxInterceptor.visitPutKeyValueCommand(TxInterceptor.java:130)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
at org.infinispan.CacheDelegate.put(CacheDelegate.java:444)
at org.infinispan.CacheSupport.put(CacheSupport.java:28)
at org.hibernate.cache.infinispan.util.CacheAdapterImpl.put(CacheAdapterImpl.java:99)
at org.hibernate.cache.infinispan.util.CacheHelper.initInternalEvict(CacheHelper.java:53)
at org.hibernate.cache.infinispan.impl.BaseRegion.establishInternalNodes(BaseRegion.java:83)
at org.hibernate.cache.infinispan.impl.BaseRegion.start(BaseRegion.java:70)
at org.hibernate.cache.infinispan.InfinispanRegionFactory.buildTimestampsRegion(InfinispanRegionFactory.java:209)
at org.hibernate.cache.UpdateTimestampsCache.<init>(UpdateTimestampsCache.java:58)
at org.hibernate.impl.SessionFactoryImpl.<init>(SessionFactoryImpl.java:396)
at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1842)
at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:902)
at org.hibernate.ejb.HibernatePersistence.createContainerEntityManagerFactory(HibernatePersistence.java:74)
at org.apache.openejb.assembler.classic.PersistenceBuilder.createEntityManagerFactory(PersistenceBuilder.java:198)
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:520)
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:461)
at org.apache.openejb.assembler.classic.Assembler.buildContainerSystem(Assembler.java:371)
at org.apache.openejb.assembler.classic.Assembler.build(Assembler.java:283)
at org.apache.openejb.OpenEJB$Instance.<init>(OpenEJB.java:130)
at org.apache.openejb.OpenEJB$Instance.<init>(OpenEJB.java:63)
at org.apache.openejb.OpenEJB.init(OpenEJB.java:276)
at org.apache.openejb.OpenEJB.init(OpenEJB.java:255)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.apache.openejb.loader.OpenEJBInstance.init(OpenEJBInstance.java:36)
at org.apache.openejb.client.LocalInitialContextFactory.init(LocalInitialContextFactory.java:71)
at org.apache.openejb.client.LocalInitialContextFactory.init(LocalInitialContextFactory.java:53)
at org.apache.openejb.client.LocalInitialContextFactory.getInitialContext(LocalInitialContextFactory.java:42)
at javax.naming.spi.NamingManager.getInitialContext(NamingManager.java:667)
at javax.naming.InitialContext.getDefaultInitCtx(InitialContext.java:288)
at javax.naming.InitialContext.init(InitialContext.java:223)
at javax.naming.InitialContext.<init>(InitialContext.java:197)
at infiniSpanTest.Runner.init(Runner.java:117)
at infiniSpanTest.Runner.main(Runner.java:424)
Caused by: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-29522,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.container.EntryFactoryImpl.acquireLock(EntryFactoryImpl.java:228)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:132)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeededAndUpdateStats(CacheLoaderInterceptor.java:217)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitGetKeyValueCommand(CacheLoaderInterceptor.java:89)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitGetKeyValueCommand(MarshalledValueInterceptor.java:168)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistReadAndInvokeNext(TxInterceptor.java:169)
at org.infinispan.interceptors.TxInterceptor.visitGetKeyValueCommand(TxInterceptor.java:160)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:95)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:51)
at org.infinispan.remoting.InboundInvocationHandlerImpl.handle(InboundInvocationHandlerImpl.java:94)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.executeCommand(CommandAwareRpcDispatcher.java:177)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.handle(CommandAwareRpcDispatcher.java:149)
at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:578)
at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:489)
at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:365)
at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.up(MessageDispatcher.java:771)
at org.jgroups.JChannel.up(JChannel.java:1465)
at org.jgroups.stack.ProtocolStack.up(ProtocolStack.java:954)
at org.jgroups.protocols.pbcast.FLUSH.up(FLUSH.java:478)
at org.jgroups.protocols.pbcast.STREAMING_STATE_TRANSFER.up(STREAMING_STATE_TRANSFER.java:265)
at org.jgroups.protocols.FRAG2.up(FRAG2.java:190)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.pbcast.GMS.up(GMS.java:888)
at org.jgroups.protocols.pbcast.STABLE.up(STABLE.java:234)
at org.jgroups.protocols.UNICAST.up(UNICAST.java:310)
at org.jgroups.protocols.pbcast.NAKACK.handleMessage(NAKACK.java:836)
at org.jgroups.protocols.pbcast.NAKACK.up(NAKACK.java:671)
at org.jgroups.protocols.VERIFY_SUSPECT.up(VERIFY_SUSPECT.java:132)
at org.jgroups.protocols.FD_ALL.up(FD_ALL.java:169)
at org.jgroups.protocols.FD_SOCK.up(FD_SOCK.java:269)
at org.jgroups.protocols.MERGE2.up(MERGE2.java:210)
at org.jgroups.protocols.Discovery.up(Discovery.java:292)
at org.jgroups.protocols.PING.up(PING.java:67)
at org.jgroups.protocols.TP.passMessageUp(TP.java:1093)
at org.jgroups.protocols.TP.access$100(TP.java:56)
at org.jgroups.protocols.TP$IncomingPacket.handleMyMessage(TP.java:1633)
at org.jgroups.protocols.TP$IncomingPacket.run(TP.java:1615)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
16-12 14:28:39,376 INFO Log4jLogStream -PersistenceUnit(name=jpacache, provider=org.hibernate.ejb.HibernatePersistence) - provider time 29929ms
16-12 14:28:39,376 INFO Log4jLogStream -Undeploying app: classpath.ear
16-12 14:28:39,672 ERROR Log4jLogStream -Application could not be deployed: classpath.ear
org.apache.openejb.OpenEJBException: Creating application failed: classpath.ear: javax.persistence.PersistenceException: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:752)
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:461)
at org.apache.openejb.assembler.classic.Assembler.buildContainerSystem(Assembler.java:371)
at org.apache.openejb.assembler.classic.Assembler.build(Assembler.java:283)
at org.apache.openejb.OpenEJB$Instance.<init>(OpenEJB.java:130)
at org.apache.openejb.OpenEJB$Instance.<init>(OpenEJB.java:63)
at org.apache.openejb.OpenEJB.init(OpenEJB.java:276)
at org.apache.openejb.OpenEJB.init(OpenEJB.java:255)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.apache.openejb.loader.OpenEJBInstance.init(OpenEJBInstance.java:36)
at org.apache.openejb.client.LocalInitialContextFactory.init(LocalInitialContextFactory.java:71)
at org.apache.openejb.client.LocalInitialContextFactory.init(LocalInitialContextFactory.java:53)
at org.apache.openejb.client.LocalInitialContextFactory.getInitialContext(LocalInitialContextFactory.java:42)
at javax.naming.spi.NamingManager.getInitialContext(NamingManager.java:667)
at javax.naming.InitialContext.getDefaultInitCtx(InitialContext.java:288)
at javax.naming.InitialContext.init(InitialContext.java:223)
at javax.naming.InitialContext.<init>(InitialContext.java:197)
at infiniSpanTest.Runner.init(Runner.java:117)
at infiniSpanTest.Runner.main(Runner.java:424)
Caused by: org.apache.openejb.OpenEJBException: javax.persistence.PersistenceException: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:525)
... 21 more
Caused by: javax.persistence.PersistenceException: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory
at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:911)
at org.hibernate.ejb.HibernatePersistence.createContainerEntityManagerFactory(HibernatePersistence.java:74)
at org.apache.openejb.assembler.classic.PersistenceBuilder.createEntityManagerFactory(PersistenceBuilder.java:198)
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:520)
... 21 more
Caused by: org.hibernate.cache.CacheException: org.infinispan.CacheException: org.infinispan.loaders.CacheLoaderException: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-29522,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.hibernate.cache.infinispan.util.CacheAdapterImpl.put(CacheAdapterImpl.java:101)
at org.hibernate.cache.infinispan.util.CacheHelper.initInternalEvict(CacheHelper.java:53)
at org.hibernate.cache.infinispan.impl.BaseRegion.establishInternalNodes(BaseRegion.java:83)
at org.hibernate.cache.infinispan.impl.BaseRegion.start(BaseRegion.java:70)
at org.hibernate.cache.infinispan.InfinispanRegionFactory.buildTimestampsRegion(InfinispanRegionFactory.java:209)
at org.hibernate.cache.UpdateTimestampsCache.<init>(UpdateTimestampsCache.java:58)
at org.hibernate.impl.SessionFactoryImpl.<init>(SessionFactoryImpl.java:396)
at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1842)
at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:902)
... 24 more
Caused by: org.infinispan.CacheException: org.infinispan.loaders.CacheLoaderException: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-29522,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:281)
at org.infinispan.CacheDelegate.put(CacheDelegate.java:444)
at org.infinispan.CacheSupport.put(CacheSupport.java:28)
at org.hibernate.cache.infinispan.util.CacheAdapterImpl.put(CacheAdapterImpl.java:99)
... 32 more
Caused by: org.infinispan.loaders.CacheLoaderException: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-29522,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.loaders.cluster.ClusterCacheLoader.doRemoteCall(ClusterCacheLoader.java:102)
at org.infinispan.loaders.cluster.ClusterCacheLoader.load(ClusterCacheLoader.java:54)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:148)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitPutKeyValueCommand(CacheLoaderInterceptor.java:79)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitPutKeyValueCommand(MarshalledValueInterceptor.java:125)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistWriteAndInvokeNext(TxInterceptor.java:182)
at org.infinispan.interceptors.TxInterceptor.visitPutKeyValueCommand(TxInterceptor.java:130)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
... 35 more
Caused by: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-29522,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.container.EntryFactoryImpl.acquireLock(EntryFactoryImpl.java:228)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:132)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeededAndUpdateStats(CacheLoaderInterceptor.java:217)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitGetKeyValueCommand(CacheLoaderInterceptor.java:89)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitGetKeyValueCommand(MarshalledValueInterceptor.java:168)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistReadAndInvokeNext(TxInterceptor.java:169)
at org.infinispan.interceptors.TxInterceptor.visitGetKeyValueCommand(TxInterceptor.java:160)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:95)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:51)
at org.infinispan.remoting.InboundInvocationHandlerImpl.handle(InboundInvocationHandlerImpl.java:94)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.executeCommand(CommandAwareRpcDispatcher.java:177)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.handle(CommandAwareRpcDispatcher.java:149)
at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:578)
at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:489)
at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:365)
at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.up(MessageDispatcher.java:771)
at org.jgroups.JChannel.up(JChannel.java:1465)
at org.jgroups.stack.ProtocolStack.up(ProtocolStack.java:954)
at org.jgroups.protocols.pbcast.FLUSH.up(FLUSH.java:478)
at org.jgroups.protocols.pbcast.STREAMING_STATE_TRANSFER.up(STREAMING_STATE_TRANSFER.java:265)
at org.jgroups.protocols.FRAG2.up(FRAG2.java:190)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.pbcast.GMS.up(GMS.java:888)
at org.jgroups.protocols.pbcast.STABLE.up(STABLE.java:234)
at org.jgroups.protocols.UNICAST.up(UNICAST.java:310)
at org.jgroups.protocols.pbcast.NAKACK.handleMessage(NAKACK.java:836)
at org.jgroups.protocols.pbcast.NAKACK.up(NAKACK.java:671)
at org.jgroups.protocols.VERIFY_SUSPECT.up(VERIFY_SUSPECT.java:132)
at org.jgroups.protocols.FD_ALL.up(FD_ALL.java:169)
at org.jgroups.protocols.FD_SOCK.up(FD_SOCK.java:269)
at org.jgroups.protocols.MERGE2.up(MERGE2.java:210)
at org.jgroups.protocols.Discovery.up(Discovery.java:292)
at org.jgroups.protocols.PING.up(PING.java:67)
at org.jgroups.protocols.TP.passMessageUp(TP.java:1093)
at org.jgroups.protocols.TP.access$100(TP.java:56)
at org.jgroups.protocols.TP$IncomingPacket.handleMyMessage(TP.java:1633)
at org.jgroups.protocols.TP$IncomingPacket.run(TP.java:1615)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
14:28:39,719 Test of node 0/2 will begin now
14:28:39,719 InfiniSpanTest is stopping its node 0/2
javax.naming.NameNotFoundException: Name "FamilyManagerBeanLocal" not found.
at org.apache.openejb.core.ivm.naming.IvmContext.federate(IvmContext.java:193)
at org.apache.openejb.core.ivm.naming.IvmContext.lookup(IvmContext.java:150)
at org.apache.openejb.core.ivm.naming.ContextWrapper.lookup(ContextWrapper.java:115)
at javax.naming.InitialContext.lookup(InitialContext.java:392)
at infiniSpanTest.JndiLookup.getResource(JndiLookup.java:22)
at infiniSpanTest.Runner.setManager(Runner.java:92)
at infiniSpanTest.Runner.<init>(Runner.java:82)
at infiniSpanTest.Runner.main(Runner.java:432)16-12 14:28:39,734 INFO Log4jLogStream -Destroying container system
16-12 14:28:39,734 INFO Log4jLogStream -Closing DataSource: jpacacheDS
16-12 14:28:39,828 INFO Log4jLogStream -Closing DataSource: jpacacheDSNonJta
and the stdout of the second node :
14:27:57,995 InfiniSpanTest is starting its node 1/2
16 déc. 2010 14:28:00 org.apache.openejb.cdi.CdiBuilder <init>
INFO: Created new singletonService org.apache.openejb.cdi.ThreadSingletonServiceImpl at 1c86be5
16 déc. 2010 14:28:00 org.apache.openejb.cdi.CdiBuilder <init>
INFO: succeeded in installing singleton service
Apache OpenEJB 3.2-SNAPSHOT build: 20101203-01:57
http://openejb.apache.org/
16-12 14:28:00,660 INFO Log4jLogStream -openejb.home = D:\work35\InfiniSpanTest
16-12 14:28:00,707 INFO Log4jLogStream -openejb.base = D:\work35\InfiniSpanTest
16-12 14:28:03,668 INFO Log4jLogStream -Configuring Service(id=Default Security Service, type=SecurityService, provider-id=Default Security Service)
16-12 14:28:03,668 INFO Log4jLogStream -Configuring Service(id=Default Transaction Manager, type=TransactionManager, provider-id=Default Transaction Manager)
16-12 14:28:03,668 INFO Log4jLogStream -Configuring Service(id=jpacacheDS, type=Resource, provider-id=Default JDBC Database)
16-12 14:28:03,668 INFO Log4jLogStream -Configuring Service(id=My Stateless Container, type=Container, provider-id=Default Stateless Container)
16-12 14:28:04,136 INFO Log4jLogStream -Found EjbModule in classpath: D:\work35\InfiniSpanTest\target\test-classes
16-12 14:28:04,432 INFO Log4jLogStream -Found EjbModule in classpath: D:\work35\InfiniSpanTest\target\classes
16-12 14:28:04,603 INFO Log4jLogStream -Beginning load: D:\work35\InfiniSpanTest\target\test-classes
16-12 14:28:04,822 INFO Log4jLogStream -Beginning load: D:\work35\InfiniSpanTest\target\classes
16-12 14:28:07,019 INFO Log4jLogStream -Configuring enterprise application: classpath.ear
16-12 14:28:07,954 INFO Log4jLogStream -Configuring PersistenceUnit(name=jpacache, provider=org.hibernate.ejb.HibernatePersistence)
16-12 14:28:07,954 INFO Log4jLogStream -Auto-creating a Resource with id 'jpacacheDSNonJta' of type 'DataSource for 'jpacache'.
16-12 14:28:07,954 INFO Log4jLogStream -Configuring Service(id=jpacacheDSNonJta, type=Resource, provider-id=jpacacheDS)
16-12 14:28:07,954 INFO Log4jLogStream -Adjusting PersistenceUnit jpacache <non-jta-data-source> to Resource ID 'jpacacheDSNonJta' from 'null'
16-12 14:28:08,079 INFO Log4jLogStream -Enterprise application "classpath.ear" loaded.
16-12 14:28:08,079 INFO Log4jLogStream -Creating TransactionManager(id=Default Transaction Manager)
16-12 14:28:08,718 INFO Log4jLogStream -Creating SecurityService(id=Default Security Service)
16-12 14:28:09,108 INFO Log4jLogStream -Creating Resource(id=jpacacheDS)
16-12 14:28:09,217 INFO Log4jLogStream -Creating Resource(id=jpacacheDSNonJta)
16-12 14:28:09,248 INFO Log4jLogStream -Creating Container(id=My Stateless Container)
16-12 14:28:09,404 INFO Log4jLogStream -Assembling app: classpath.ear
16-12 14:28:17,228 INFO JGroupsTransport -Starting JGroups Channel
-------------------------------------------------------------------
GMS: address=W20349-29522, cluster=infinispan-hibernate-cluster, physical address=10.8.245.147:35754
-------------------------------------------------------------------
16-12 14:28:22,372 INFO AbstractLogImpl -Received new cluster view: [W20349-46606|1] [W20349-46606, W20349-29522]
16-12 14:28:22,465 INFO AbstractLogImpl -Cache local address is W20349-29522, physical addresses are [10.8.245.147:35754]
16-12 14:28:22,465 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:22,512 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:22,964 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:22,995 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:23,042 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:23,088 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:23,385 INFO AbstractComponentRegistry -Infinispan version: Infinispan 'Ursus' 4.2.0-SNAPSHOT
16-12 14:28:39,204 ERROR InvocationContextInterceptor -Execution error:
org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-29522,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.container.EntryFactoryImpl.acquireLock(EntryFactoryImpl.java:228)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:132)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeededAndUpdateStats(CacheLoaderInterceptor.java:217)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitGetKeyValueCommand(CacheLoaderInterceptor.java:89)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitGetKeyValueCommand(MarshalledValueInterceptor.java:168)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistReadAndInvokeNext(TxInterceptor.java:169)
at org.infinispan.interceptors.TxInterceptor.visitGetKeyValueCommand(TxInterceptor.java:160)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:95)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:51)
at org.infinispan.remoting.InboundInvocationHandlerImpl.handle(InboundInvocationHandlerImpl.java:94)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.executeCommand(CommandAwareRpcDispatcher.java:177)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.handle(CommandAwareRpcDispatcher.java:149)
at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:578)
at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:489)
at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:365)
at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.up(MessageDispatcher.java:771)
at org.jgroups.JChannel.up(JChannel.java:1465)
at org.jgroups.stack.ProtocolStack.up(ProtocolStack.java:954)
at org.jgroups.protocols.pbcast.FLUSH.up(FLUSH.java:478)
at org.jgroups.protocols.pbcast.STREAMING_STATE_TRANSFER.up(STREAMING_STATE_TRANSFER.java:265)
at org.jgroups.protocols.FRAG2.up(FRAG2.java:190)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.pbcast.GMS.up(GMS.java:888)
at org.jgroups.protocols.pbcast.STABLE.up(STABLE.java:234)
at org.jgroups.protocols.UNICAST.up(UNICAST.java:310)
at org.jgroups.protocols.pbcast.NAKACK.handleMessage(NAKACK.java:836)
at org.jgroups.protocols.pbcast.NAKACK.up(NAKACK.java:671)
at org.jgroups.protocols.VERIFY_SUSPECT.up(VERIFY_SUSPECT.java:132)
at org.jgroups.protocols.FD_ALL.up(FD_ALL.java:169)
at org.jgroups.protocols.FD_SOCK.up(FD_SOCK.java:269)
at org.jgroups.protocols.MERGE2.up(MERGE2.java:210)
at org.jgroups.protocols.Discovery.up(Discovery.java:292)
at org.jgroups.protocols.PING.up(PING.java:67)
at org.jgroups.protocols.TP.passMessageUp(TP.java:1093)
at org.jgroups.protocols.TP.access$100(TP.java:56)
at org.jgroups.protocols.TP$IncomingPacket.handleMyMessage(TP.java:1633)
at org.jgroups.protocols.TP$IncomingPacket.run(TP.java:1615)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
16-12 14:28:39,298 ERROR InvocationContextInterceptor -Execution error:
org.infinispan.loaders.CacheLoaderException: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-46606,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.loaders.cluster.ClusterCacheLoader.doRemoteCall(ClusterCacheLoader.java:102)
at org.infinispan.loaders.cluster.ClusterCacheLoader.load(ClusterCacheLoader.java:54)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:148)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitPutKeyValueCommand(CacheLoaderInterceptor.java:79)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitPutKeyValueCommand(MarshalledValueInterceptor.java:125)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistWriteAndInvokeNext(TxInterceptor.java:182)
at org.infinispan.interceptors.TxInterceptor.visitPutKeyValueCommand(TxInterceptor.java:130)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
at org.infinispan.CacheDelegate.put(CacheDelegate.java:444)
at org.infinispan.CacheSupport.put(CacheSupport.java:28)
at org.hibernate.cache.infinispan.util.CacheAdapterImpl.put(CacheAdapterImpl.java:99)
at org.hibernate.cache.infinispan.util.CacheHelper.initInternalEvict(CacheHelper.java:53)
at org.hibernate.cache.infinispan.impl.BaseRegion.establishInternalNodes(BaseRegion.java:83)
at org.hibernate.cache.infinispan.impl.BaseRegion.start(BaseRegion.java:70)
at org.hibernate.cache.infinispan.InfinispanRegionFactory.buildTimestampsRegion(InfinispanRegionFactory.java:209)
at org.hibernate.cache.UpdateTimestampsCache.<init>(UpdateTimestampsCache.java:58)
at org.hibernate.impl.SessionFactoryImpl.<init>(SessionFactoryImpl.java:396)
at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1842)
at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:902)
at org.hibernate.ejb.HibernatePersistence.createContainerEntityManagerFactory(HibernatePersistence.java:74)
at org.apache.openejb.assembler.classic.PersistenceBuilder.createEntityManagerFactory(PersistenceBuilder.java:198)
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:520)
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:461)
at org.apache.openejb.assembler.classic.Assembler.buildContainerSystem(Assembler.java:371)
at org.apache.openejb.assembler.classic.Assembler.build(Assembler.java:283)
at org.apache.openejb.OpenEJB$Instance.<init>(OpenEJB.java:130)
at org.apache.openejb.OpenEJB$Instance.<init>(OpenEJB.java:63)
at org.apache.openejb.OpenEJB.init(OpenEJB.java:276)
at org.apache.openejb.OpenEJB.init(OpenEJB.java:255)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.apache.openejb.loader.OpenEJBInstance.init(OpenEJBInstance.java:36)
at org.apache.openejb.client.LocalInitialContextFactory.init(LocalInitialContextFactory.java:71)
at org.apache.openejb.client.LocalInitialContextFactory.init(LocalInitialContextFactory.java:53)
at org.apache.openejb.client.LocalInitialContextFactory.getInitialContext(LocalInitialContextFactory.java:42)
at javax.naming.spi.NamingManager.getInitialContext(NamingManager.java:667)
at javax.naming.InitialContext.getDefaultInitCtx(InitialContext.java:288)
at javax.naming.InitialContext.init(InitialContext.java:223)
at javax.naming.InitialContext.<init>(InitialContext.java:197)
at infiniSpanTest.Runner.init(Runner.java:117)
at infiniSpanTest.Runner.main(Runner.java:424)
Caused by: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-46606,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.container.EntryFactoryImpl.acquireLock(EntryFactoryImpl.java:228)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:132)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeededAndUpdateStats(CacheLoaderInterceptor.java:217)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitGetKeyValueCommand(CacheLoaderInterceptor.java:89)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitGetKeyValueCommand(MarshalledValueInterceptor.java:168)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistReadAndInvokeNext(TxInterceptor.java:169)
at org.infinispan.interceptors.TxInterceptor.visitGetKeyValueCommand(TxInterceptor.java:160)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:95)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:51)
at org.infinispan.remoting.InboundInvocationHandlerImpl.handle(InboundInvocationHandlerImpl.java:94)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.executeCommand(CommandAwareRpcDispatcher.java:177)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.handle(CommandAwareRpcDispatcher.java:149)
at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:578)
at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:489)
at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:365)
at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.up(MessageDispatcher.java:771)
at org.jgroups.JChannel.up(JChannel.java:1465)
at org.jgroups.stack.ProtocolStack.up(ProtocolStack.java:954)
at org.jgroups.protocols.pbcast.FLUSH.up(FLUSH.java:478)
at org.jgroups.protocols.pbcast.STREAMING_STATE_TRANSFER.up(STREAMING_STATE_TRANSFER.java:265)
at org.jgroups.protocols.FRAG2.up(FRAG2.java:190)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.pbcast.GMS.up(GMS.java:888)
at org.jgroups.protocols.pbcast.STABLE.up(STABLE.java:234)
at org.jgroups.protocols.UNICAST.up(UNICAST.java:310)
at org.jgroups.protocols.pbcast.NAKACK.handleMessage(NAKACK.java:836)
at org.jgroups.protocols.pbcast.NAKACK.up(NAKACK.java:671)
at org.jgroups.protocols.VERIFY_SUSPECT.up(VERIFY_SUSPECT.java:132)
at org.jgroups.protocols.FD_ALL.up(FD_ALL.java:169)
at org.jgroups.protocols.FD_SOCK.up(FD_SOCK.java:269)
at org.jgroups.protocols.MERGE2.up(MERGE2.java:210)
at org.jgroups.protocols.Discovery.up(Discovery.java:292)
at org.jgroups.protocols.PING.up(PING.java:67)
at org.jgroups.protocols.TP.passMessageUp(TP.java:1093)
at org.jgroups.protocols.TP.access$100(TP.java:56)
at org.jgroups.protocols.TP$IncomingPacket.handleMyMessage(TP.java:1633)
at org.jgroups.protocols.TP$IncomingPacket.run(TP.java:1615)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
16-12 14:28:39,391 INFO Log4jLogStream -PersistenceUnit(name=jpacache, provider=org.hibernate.ejb.HibernatePersistence) - provider time 29934ms
16-12 14:28:39,391 INFO Log4jLogStream -Undeploying app: classpath.ear
16-12 14:28:39,672 ERROR Log4jLogStream -Application could not be deployed: classpath.ear
org.apache.openejb.OpenEJBException: Creating application failed: classpath.ear: javax.persistence.PersistenceException: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:752)
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:461)
at org.apache.openejb.assembler.classic.Assembler.buildContainerSystem(Assembler.java:371)
at org.apache.openejb.assembler.classic.Assembler.build(Assembler.java:283)
at org.apache.openejb.OpenEJB$Instance.<init>(OpenEJB.java:130)
at org.apache.openejb.OpenEJB$Instance.<init>(OpenEJB.java:63)
at org.apache.openejb.OpenEJB.init(OpenEJB.java:276)
at org.apache.openejb.OpenEJB.init(OpenEJB.java:255)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.apache.openejb.loader.OpenEJBInstance.init(OpenEJBInstance.java:36)
at org.apache.openejb.client.LocalInitialContextFactory.init(LocalInitialContextFactory.java:71)
at org.apache.openejb.client.LocalInitialContextFactory.init(LocalInitialContextFactory.java:53)
at org.apache.openejb.client.LocalInitialContextFactory.getInitialContext(LocalInitialContextFactory.java:42)
at javax.naming.spi.NamingManager.getInitialContext(NamingManager.java:667)
at javax.naming.InitialContext.getDefaultInitCtx(InitialContext.java:288)
at javax.naming.InitialContext.init(InitialContext.java:223)
at javax.naming.InitialContext.<init>(InitialContext.java:197)
at infiniSpanTest.Runner.init(Runner.java:117)
at infiniSpanTest.Runner.main(Runner.java:424)
Caused by: org.apache.openejb.OpenEJBException: javax.persistence.PersistenceException: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:525)
... 21 more
Caused by: javax.persistence.PersistenceException: [PersistenceUnit: jpacache] Unable to build EntityManagerFactory
at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:911)
at org.hibernate.ejb.HibernatePersistence.createContainerEntityManagerFactory(HibernatePersistence.java:74)
at org.apache.openejb.assembler.classic.PersistenceBuilder.createEntityManagerFactory(PersistenceBuilder.java:198)
at org.apache.openejb.assembler.classic.Assembler.createApplication(Assembler.java:520)
... 21 more
Caused by: org.hibernate.cache.CacheException: org.infinispan.CacheException: org.infinispan.loaders.CacheLoaderException: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-46606,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.hibernate.cache.infinispan.util.CacheAdapterImpl.put(CacheAdapterImpl.java:101)
at org.hibernate.cache.infinispan.util.CacheHelper.initInternalEvict(CacheHelper.java:53)
at org.hibernate.cache.infinispan.impl.BaseRegion.establishInternalNodes(BaseRegion.java:83)
at org.hibernate.cache.infinispan.impl.BaseRegion.start(BaseRegion.java:70)
at org.hibernate.cache.infinispan.InfinispanRegionFactory.buildTimestampsRegion(InfinispanRegionFactory.java:209)
at org.hibernate.cache.UpdateTimestampsCache.<init>(UpdateTimestampsCache.java:58)
at org.hibernate.impl.SessionFactoryImpl.<init>(SessionFactoryImpl.java:396)
at org.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:1842)
at org.hibernate.ejb.Ejb3Configuration.buildEntityManagerFactory(Ejb3Configuration.java:902)
... 24 more
Caused by: org.infinispan.CacheException: org.infinispan.loaders.CacheLoaderException: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-46606,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:281)
at org.infinispan.CacheDelegate.put(CacheDelegate.java:444)
at org.infinispan.CacheSupport.put(CacheSupport.java:28)
at org.hibernate.cache.infinispan.util.CacheAdapterImpl.put(CacheAdapterImpl.java:99)
... 32 more
Caused by: org.infinispan.loaders.CacheLoaderException: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-46606,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.loaders.cluster.ClusterCacheLoader.doRemoteCall(ClusterCacheLoader.java:102)
at org.infinispan.loaders.cluster.ClusterCacheLoader.load(ClusterCacheLoader.java:54)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:148)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitPutKeyValueCommand(CacheLoaderInterceptor.java:79)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitPutKeyValueCommand(MarshalledValueInterceptor.java:125)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistWriteAndInvokeNext(TxInterceptor.java:182)
at org.infinispan.interceptors.TxInterceptor.visitPutKeyValueCommand(TxInterceptor.java:130)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:58)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:76)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
... 35 more
Caused by: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll at 9205714e] for requestor [Thread[Incoming-1,W20349-46606,5,Thread Pools]]! Lock held by [Thread[main,5,main]]
at org.infinispan.container.EntryFactoryImpl.acquireLock(EntryFactoryImpl.java:228)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeeded(CacheLoaderInterceptor.java:132)
at org.infinispan.interceptors.CacheLoaderInterceptor.loadIfNeededAndUpdateStats(CacheLoaderInterceptor.java:217)
at org.infinispan.interceptors.CacheLoaderInterceptor.visitGetKeyValueCommand(CacheLoaderInterceptor.java:89)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.MarshalledValueInterceptor.visitGetKeyValueCommand(MarshalledValueInterceptor.java:168)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.enlistReadAndInvokeNext(TxInterceptor.java:169)
at org.infinispan.interceptors.TxInterceptor.visitGetKeyValueCommand(TxInterceptor.java:160)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:87)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:58)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.hibernate.cache.infinispan.impl.ClassLoaderAwareCache$ClassLoaderAwareCommandInterceptor.handleDefault(ClassLoaderAwareCache.java:79)
at org.infinispan.commands.AbstractVisitor.visitGetKeyValueCommand(AbstractVisitor.java:88)
at org.infinispan.commands.read.GetKeyValueCommand.acceptVisitor(GetKeyValueCommand.java:59)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:273)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:95)
at org.infinispan.commands.remote.ClusteredGetCommand.perform(ClusteredGetCommand.java:51)
at org.infinispan.remoting.InboundInvocationHandlerImpl.handle(InboundInvocationHandlerImpl.java:94)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.executeCommand(CommandAwareRpcDispatcher.java:177)
at org.infinispan.remoting.transport.jgroups.CommandAwareRpcDispatcher.handle(CommandAwareRpcDispatcher.java:149)
at org.jgroups.blocks.RequestCorrelator.handleRequest(RequestCorrelator.java:578)
at org.jgroups.blocks.RequestCorrelator.receiveMessage(RequestCorrelator.java:489)
at org.jgroups.blocks.RequestCorrelator.receive(RequestCorrelator.java:365)
at org.jgroups.blocks.MessageDispatcher$ProtocolAdapter.up(MessageDispatcher.java:771)
at org.jgroups.JChannel.up(JChannel.java:1465)
at org.jgroups.stack.ProtocolStack.up(ProtocolStack.java:954)
at org.jgroups.protocols.pbcast.FLUSH.up(FLUSH.java:478)
at org.jgroups.protocols.pbcast.STREAMING_STATE_TRANSFER.up(STREAMING_STATE_TRANSFER.java:265)
at org.jgroups.protocols.FRAG2.up(FRAG2.java:190)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.FlowControl.up(FlowControl.java:419)
at org.jgroups.protocols.pbcast.GMS.up(GMS.java:888)
at org.jgroups.protocols.pbcast.STABLE.up(STABLE.java:234)
at org.jgroups.protocols.UNICAST.up(UNICAST.java:310)
at org.jgroups.protocols.pbcast.NAKACK.handleMessage(NAKACK.java:836)
at org.jgroups.protocols.pbcast.NAKACK.up(NAKACK.java:671)
at org.jgroups.protocols.VERIFY_SUSPECT.up(VERIFY_SUSPECT.java:132)
at org.jgroups.protocols.FD_ALL.up(FD_ALL.java:169)
at org.jgroups.protocols.FD_SOCK.up(FD_SOCK.java:269)
at org.jgroups.protocols.MERGE2.up(MERGE2.java:210)
at org.jgroups.protocols.Discovery.up(Discovery.java:292)
at org.jgroups.protocols.PING.up(PING.java:67)
at org.jgroups.protocols.TP.passMessageUp(TP.java:1093)
at org.jgroups.protocols.TP.access$100(TP.java:56)
at org.jgroups.protocols.TP$IncomingPacket.handleMyMessage(TP.java:1633)
at org.jgroups.protocols.TP$IncomingPacket.run(TP.java:1615)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
javax.naming.NameNotFoundException: Name "FamilyManagerBeanLocal" not found.
14:28:39,734 Test of node 1/2 will begin now
at org.apache.openejb.core.ivm.naming.IvmContext.federate(IvmContext.java:193)
at org.apache.openejb.core.ivm.naming.IvmContext.lookup(IvmContext.java:150)
at org.apache.openejb.core.ivm.naming.ContextWrapper.lookup(ContextWrapper.java:115)
at javax.naming.InitialContext.lookup(InitialContext.java:392)
at infiniSpanTest.JndiLookup.getResource(JndiLookup.java:22)
at infiniSpanTest.Runner.setManager(Runner.java:92)
at infiniSpanTest.Runner.<init>(Runner.java:82)
at infiniSpanTest.Runner.main(Runner.java:432)
14:28:39,812 InfiniSpanTest is stopping its node 1/2
16-12 14:28:39,828 INFO Log4jLogStream -Destroying container system
16-12 14:28:39,843 INFO Log4jLogStream -Closing DataSource: jpacacheDS
16-12 14:28:39,890 INFO Log4jLogStream -Closing DataSource: jpacacheDSNonJta
> TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
> ----------------------------------------------------------------------------------------
>
> Key: ISPN-832
> URL: https://issues.jboss.org/browse/ISPN-832
> Project: Infinispan
> Issue Type: Bug
> Components: State transfer
> Affects Versions: 4.2.0.CR3
> Environment: Windows XP + Hibernate 3.6.1-SNAPSHOT + OpenEjb 3.2-SNAPSHOT
> Reporter: Cyrille Charron
> Assignee: Galder Zamarreño
> Attachments: InfiniSpanTest.zip
>
>
> Hello,
> I have some difficulties to run more than one Node
> with Infinispan as a JPA Level2 cache (default configuration)
> with Hibernate as JPA provider and
> with OpenEjb as JEE container.
> There are a lot of Locks between Nodes when I try to insert entities,
> even if each Node insert entities with a unique ID.
--
This message is automatically generated by JIRA.
-
For more information on JIRA, see: http://www.atlassian.com/software/jira
More information about the infinispan-issues
mailing list