[JBoss JIRA] Created: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
8:22 a.m.
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: Manik Surtani
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
8:27 a.m.
New subject: [JBoss JIRA] Updated: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron updated ISPN-832:
---------------------------------
Attachment: InfiniSpanTest.zip
An eclipse project running the test.
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: Manik Surtani
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
9:32 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Manik Surtani commented on ISPN-832:
------------------------------------
Have you looked at
http://community.jboss.org/wiki/UsingInfinispanasJPAHibernateSecondLevelC... and
tried to configure Infinispan according to that doc?
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: Manik Surtani
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
9:46 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
Yes, I use the JPA version configuration (you will see the values in the persistence.xml
file of the test project), I use the default configuration for Infinispan (no explicit
file).
Here the extract of my persistence file :
<persistence-unit name="jpacache" transaction-type="JTA">
<provider>org.hibernate.ejb.HibernatePersistence</provider>
<jta-data-source>java:openejb/Resource/jpacacheDS</jta-data-source>
<mapping-file>META-INF/orm.xml</mapping-file>
<class>infiniSpanTest.Person</class>
<class>infiniSpanTest.FemalePerson</class>
<class>infiniSpanTest.MalePerson</class>
<class>infiniSpanTest.Address</class>
<class>infiniSpanTest.Family</class>
<properties>
<property name="hibernate.dialect"
value="org.hibernate.dialect.HSQLDialect" />
<property name="hibernate.show_sql" value="false"
/>
<property name="hibernate.hbm2ddl.auto"
value="create"/>
<property name="hibernate.transaction.manager_lookup_class"
value="org.apache.openejb.hibernate.TransactionManagerLookup" />
<property name="hibernate.generate_statistics"
value="true" />
<property name="hibernate.cache.use_structured_entries"
value="true" />
<property name="hibernate.cache.use_second_level_cache"
value="true" />
<property name="hibernate.cache.use_query_cache"
value="true" />
<property name="hibernate.cache.region.factory_class"
value="org.hibernate.cache.infinispan.InfinispanRegionFactory"
/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Family.cfg"
value="family-entity"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Person.cfg"
value="person-entity"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Address.cfg"
value="address-entity"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Family.members.cfg"
value="members-collection"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Address.inhabitants.cfg"
value="inhabitants-collection"/>
<property name="hibernate.ejb.classcache.infiniSpanTest.Family"
value="transactional" />
<property name="hibernate.ejb.classcache.infiniSpanTest.Person"
value="transactional" />
<property name="hibernate.ejb.classcache.infiniSpanTest.Address"
value="transactional" />
<property
name="hibernate.ejb.collectioncache.infiniSpanTest.Family.members"
value="transactional" />
<property
name="hibernate.ejb.collectioncache.infiniSpanTest.Address.inhabitants"
value="transactional" />
</properties>
</persistence-unit>
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: Manik Surtani
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
9:54 a.m.
New subject: [JBoss JIRA] Assigned: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño reassigned ISPN-832:
-------------------------------------
Assignee: Galder Zamarreño (was: Manik Surtani)
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
10:29 a.m.
New subject: [JBoss JIRA] Updated: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron updated ISPN-832:
---------------------------------
Steps to Reproduce:
I join a zip file of my maven project which I run under Eclipse Helios.
As you will see, I use the default configuration of InfiniSpan.
I have tested with no L2 cache (all is good) and with Infinispan L2 cache (all is bad).
I have tested with Hsqldb and MySql databases (my tests encounters the same Locks
apparently).
I have tested with 8 or 32 threads, and for 10 or 1000 requets per thread
(cf. lines 19-23 in src/test/java/infiniSpanTest/Runner.java).
Don't expect good performances ... I have added a lot of calls
to System.out.println() to show my threads running (and waiting).
Running the test :
------------------
You can run the Hsqldb test by :
starting an Hsqldb database with startHsqldb.bat
start the first node with InfiniSpanRun-1.launch
start the second node with InfiniSpanRun-2.launch
With a MySql database, you must obviously start this database
and you must empty tables before running the test nodes
(in case of a previous test didn't finished) :
start the first node with InfiniSpanRun-1.launch
start the second node with InfiniSpanRun-2.launch
With Hsqldb or MySql database configuration :
---------------------------------------------
If you want to test with MySql, you must update the following files :
src/main/java/META-INF/persistence.xml
change hibernate.dialect
conf/openejb.xml
change JdbcDriver
change JdbcUrl
change UserName
change Password
change ValidationQuery
With or without infinispan configuration :
------------------------------------------
If you want to test without Infinispan, you must update the following files :
src/main/java/META-INF/persistence.xml
uncomment the pêrsistence unit without cache
comment the persistence unit with cache
was:
I join a zip file of my maven project which I run under Eclipse Helios.
As you will see, I use the default configuration of InfiniSpan.
I have tested with no L2 cache (all is good) and with Infinispan L2 cache (all is bad).
I have tested with Hsqldb and MySql databases (my tests encounters the same Locks
apparently).
I have tested with 8 or 32 threads, and for 10 or 1000 requets per thread
(cf. lines 19-23 in src/test/java/infiniSpanTest/Runner.java).
Don't expect good performances ... I have added a lot of calls
to System.out.println() to show my threads running (and waiting).
Running the test :
------------------
You can run the Hsqldb test by :
starting an Hsqldb database with startHsqldb.bat
start the first node with InfiniSpanRun-1.launch
start the second node with InfiniSpanRun-2.launch
With a MySql database, you must obviously start this database
and you must empty tables before running the test nodes
(in case of a previous test didn't finished) :
start the first node with InfiniSpanRun-1.launch
start the second node with InfiniSpanRun-2.launch
With Hsqldb or MySql database configuration :
---------------------------------------------
If you want to test with MySql, you must update the following files :
src/main/java/META-INF/persistence.xml
change hibernate.dialect
conf/openejb.xml
change JdbcDriver
change JdbcUrl
change UserName
change Password
change ValidationQuery
With or without infinispan configuration :
------------------------------------------
If you want to test without Infinispan, you must update the following files :
src/main/java/META-INF/persistence.xml
uncomment the pêrsistence unit without cache
comment the persistence unit with cache
I replaced tabs by spaces, to be more readable.
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
5:07 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
The lock timeouts appear to come from updating the timestamps cache. You should try your
test with query cache disabled. I'm not sure about the root cause but I'm
investigating it.
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
9:15 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
The problem is that the timestamp cache should be replicated asynchronously but it's
replicated synchronously due to state transfer being enabled. So, effectively this is
putting strain in the timestamp cache which is very chatty.
I'll create a JIRA in the mean time to deal with this better, but the quick solution
is to disable state transfer in update timestamps and instead use a clustered cache
loader. I'll try this out now.
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
9:17 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
Right. I have no more lock timeout when disabling query cache.
But we need it. So i'am impatient you find the root cause :-)
By the way, i have a surprising behaviour which may help you in finding the root cause.
When i disable the following "transactional" parameters or the following
"region cache name" parameters in my persistence.xml file, read entity
performances are roughly 10 times worse.
"transactional" parameters :
<property name="hibernate.ejb.classcache.infiniSpanTest.Family"
value="transactional" />
<property name="hibernate.ejb.classcache.infiniSpanTest.Person"
value="transactional" />
<property name="hibernate.ejb.classcache.infiniSpanTest.Address"
value="transactional" />
<property
name="hibernate.ejb.collectioncache.infiniSpanTest.Family.members"
value="transactional" />
<property
name="hibernate.ejb.collectioncache.infiniSpanTest.Address.inhabitants"
value="transactional" />
"region cache name" parameters :
<property
name="hibernate.cache.infinispan.infiniSpanTest.Family.cfg"
value="family-entity"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Person.cfg"
value="person-entity"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Address.cfg"
value="address-entity"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Family.members.cfg"
value="members-collection"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Address.inhabitants.cfg"
value="inhabitants-collection"/>
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
9:51 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
We already know the root cause Cyrille, see last comment :). Basically, timestampt cache
is being updated synchronously when it should be asynchronously. The workaround is to
switch from state transfer to using a cluster cache loader. I'm doing some tests to
verify that it all works fine with this change.
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
10:28 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
Lol. No problem !
I started my comment a long time before yours, but i commited mine only 3 minutes after
yours. Too late !
So, i saw your comment only after having commited my own comment :-)
Nevertheless, this behaviour is still strange for me !
Since i don't know what do you mean by using a "cluster cache loader",
i'm hoping you will show me the config change i will have to apply.
By the way, thanks for all your work :-)
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
11:22 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
Of course I will show you the config, once I've made sure it all works fine with the
change :)
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
1:36 p.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
Cyrille, the config file should look like this:
https://github.com/hibernate/hibernate-core/blob/c7decef9b13e48f942ab1b1e...
But on top of that, to maintain same semantics as before, you need to take Hibernate 3.6
and patch it with the code changes in
https://github.com/hibernate/hibernate-core/commit/c7decef9b13e48f942ab1b...
I'm now closing the JIRA and will link to the real issue in ISPN-835
FYI, the Hibernate issue is:
http://opensource.atlassian.com/projects/hibernate/browse/HHH-5793
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
1:38 p.m.
New subject: [JBoss JIRA] Resolved: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño resolved ISPN-832.
-----------------------------------
Resolution: Deferred
The root cause is explained in ISPN-835 which indicates what needs improving.
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
1:59 p.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
I've added an FAQ:
http://community.jboss.org/wiki/InfinispanTechnicalFAQs#I_have_caches_con...
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
5:06 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
I'm sorry, i don't have good news.
I'am using the 3.6.1 snapshot version of hibernate, which integrates your patch in
hibernate-infinispan.
I have enabled the query cache et added the specific infinispan-configs.xml file.
But my tests are still failing in TimeoutException, sometimes at the start, sometimes
later during inserts or updates.
I will again verify all my configuration...
If this persists, can i join some traces in this issue or should i open a new issue ?
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
5:12 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
Well Cyrille, I haven't uploaded a new Hibernate 3.6 snapshot since I committed this
and I dunno whether anyone did it.
So, why don't you clone the Hibernate Github repo, checkout branch 3.6, build core,
testing and cache-infinispan projects and then try again?
https://github.com/hibernate/hibernate-core
git clone <take clone URL from link above>
git checkout 3.6
mvn -Dmaven.test.skip.exec=true clean install -pl core,testing,cache-infinispan
This will guarantee that your picking my code and configuration changes.
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
8:42 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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
8:50 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
I forgot to say that the error appears at the starting of the nodes, while OpenEjb is
trying to construct its beans.
I didn't even started the applicative test with its insertions, updates, reads and
removes.
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
11:39 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
Even though it's a TimeoutException, it's a different cause. I'll have a look
tomorrow.
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
2:02 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
I think so. The 2 nodes run and fail an "EvictAll" at the start.
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
10:05 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
I'm out for 2 weeks (Christmas holidays), so see you later in 2 weeks ...
Have a merry Christmas and a happy new year.
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
10:41 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
Cyrille, I've tried to replicate your latest exceptions repeteadly and haven't had
success. I'm starting both tests pretty much in paralell and they seem to work fine.
How easily are you replicating these new exceptions? Have you changed the test in any
way?
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
6:45 p.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
At work, i managed to reproduce the bug nearly on every try. At home, i manage to
reproduce it nearly 1 time every 4 tries.
I noticed that you have to start the 2 programs nearly at the same time (less than 0.5
second).
At home, i must start some other programs, like music player, to slow my computer and have
sufficient time to start the 2 programs.
You must see the messages "Infinispan 'Ursus' 4.2.0-SNAPSHOT" of the
second node appears before all the 8 same messages of the first node are displayed.
The bug appears only when the message "AbstractLogImpl -Received new cluster view:
[...] [..., ...]"appears on the first node before its last message "Infinispan
'Ursus' 4.2.0-SNAPSHOT" appears. The stack trace is not apparently the same
each time, but i there is always a TimeoutException somewhere.
At work, as you can see in logs, i have 0.8 second, at home i have only 0.2 second.
First node log :
...
18-12 00:34:28,062 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 00:34:28,072 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 00:34:28,180 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 00:34:28,187 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 00:34:28,194 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 00:34:28,205 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 00:34:28,227 INFO AbstractLogImpl -Received new cluster view: [ccc1-20189|1]
[ccc1-20189, ccc1-58673]
18-12 00:34:28,275 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
...
Second node log :
...
18-12 00:34:28,252 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 00:34:28,262 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
...
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
7:10 p.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
I manage to reproduce the bug more easily by adding the following code in Runner.java
before calling my Runner.init() function which starts OpenEjb :
System.out.println(sdf.format(new Date()) + " Strike any key to start");
while (System.in.available() > 0) System.in.skip(System.in.available());
System.in.read();
Runner.init();
Then, under Eclipse after having started the 2 programs, i open 1 more console view and
place it elsewhere on the screen until seeing simultaneaously the 2 consoles : one on the
first run, the second on the second node.
I select one of the 2 consoles, i move my mouse over the second console, then i have only
to click ENTER keyboard (it starts the first node), to click LEFT mouse button (it
activates focus on the second console), and to click ENTER keyboard again (it starts the
second node).
The content of my infinispan-configs.xml file is (comments removed) :
<?xml version="1.0" encoding="UTF-8"?>
<infinispan
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="urn:infinispan:config:4.2
http://www.infinispan.org/schemas/infinispan-config-4.2.xsd"
xmlns="urn:infinispan:config:4.2">
<global>
<transport transportClass =
"org.infinispan.remoting.transport.jgroups.JGroupsTransport"
clusterName="infinispan-hibernate-cluster"
distributedSyncTimeout="50000">
<properties>
<property name="configurationFile"
value="flush-udp.xml"/>
</properties>
</transport>
</global>
<default>
<jmxStatistics enabled="false"/>
</default>
<namedCache name="entity">
<clustering mode="invalidation">
<stateRetrieval fetchInMemoryState="false"
timeout="20000"/>
<sync replTimeout="20000"/>
</clustering>
<locking isolationLevel="READ_COMMITTED"
concurrencyLevel="1000"
lockAcquisitionTimeout="15000" useLockStriping="false"
/>
<eviction wakeUpInterval="5000" maxEntries="10000"
strategy="LRU"/>
<expiration maxIdle="100000"/>
<lazyDeserialization enabled="true"/>
</namedCache>
<namedCache name="entity-repeatable">
<clustering mode="invalidation">
<stateRetrieval fetchInMemoryState="false"
timeout="20000"/>
<sync replTimeout="20000"/>
</clustering>
<locking isolationLevel="REPEATABLE_READ"
concurrencyLevel="1000"
lockAcquisitionTimeout="15000"
useLockStriping="false"/>
<eviction wakeUpInterval="5000" maxEntries="10000"
strategy="LRU"/>
<expiration maxIdle="100000"/>
<lazyDeserialization enabled="true"/>
</namedCache>
<namedCache name="replicated-entity">
<clustering mode="replication">
<stateRetrieval fetchInMemoryState="false"
timeout="20000"/>
<sync replTimeout="20000"/>
</clustering>
<locking isolationLevel="READ_COMMITTED"
concurrencyLevel="1000"
lockAcquisitionTimeout="15000"
useLockStriping="false"/>
<eviction wakeUpInterval="5000" maxEntries="10000"
strategy="LRU"/>
<expiration maxIdle="100000"/>
<lazyDeserialization enabled="true"/>
</namedCache>
<namedCache name="local-query">
<locking isolationLevel="READ_COMMITTED"
concurrencyLevel="1000"
lockAcquisitionTimeout="15000"
useLockStriping="false"/>
<eviction wakeUpInterval="5000" maxEntries="10000"
strategy="LRU"/>
<expiration maxIdle="100000"/>
</namedCache>
<namedCache name="replicated-query">
<clustering mode="replication">
<async/>
</clustering>
<locking isolationLevel="READ_COMMITTED"
concurrencyLevel="1000"
lockAcquisitionTimeout="15000"
useLockStriping="false"/>
<eviction wakeUpInterval="5000" maxEntries="10000"
strategy="LRU"/>
<expiration maxIdle="100000"/>
<loaders passivation="false" shared="false"
preload="false">
<loader class="org.infinispan.loaders.cluster.ClusterCacheLoader"
fetchPersistentState="false"
ignoreModifications="false"
purgeOnStartup="false">
<properties>
<property name="remoteCallTimeout"
value="20000"/>
</properties>
</loader>
</loaders>
</namedCache>
<namedCache name="timestamps">
<clustering mode="replication">
<async/>
</clustering>
<locking isolationLevel="READ_COMMITTED"
concurrencyLevel="1000"
lockAcquisitionTimeout="15000"
useLockStriping="false"/>
<lazyDeserialization enabled="true"/>
<eviction wakeUpInterval="0" strategy="NONE"/>
<loaders passivation="false" shared="false"
preload="false">
<loader class="org.infinispan.loaders.cluster.ClusterCacheLoader"
fetchPersistentState="false"
ignoreModifications="false"
purgeOnStartup="false">
<properties>
<property name="remoteCallTimeout"
value="20000"/>
</properties>
</loader>
</loaders>
</namedCache>
</infinispan>
And the content of my persistence.xml file is (comments removed) :
<?xml version="1.0" encoding="UTF-8"?>
<persistence xmlns="http://java.sun.com/xml/ns/persistence"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="http://java.sun.com/xml/ns/persistence
http://java.sun.com/xml/ns/persistence/persistence_2_0.xsd"
version="2.0">
<persistence-unit name="jpacache" transaction-type="JTA">
<provider>org.hibernate.ejb.HibernatePersistence</provider>
<jta-data-source>java:openejb/Resource/jpacacheDS</jta-data-source>
<mapping-file>META-INF/orm.xml</mapping-file>
<class>infiniSpanTest.Person</class>
<class>infiniSpanTest.FemalePerson</class>
<class>infiniSpanTest.MalePerson</class>
<class>infiniSpanTest.Address</class>
<class>infiniSpanTest.Family</class>
<properties>
<property name="hibernate.dialect"
value="org.hibernate.dialect.HSQLDialect" />
<property name="hibernate.show_sql" value="false"
/>
<property name="hibernate.hbm2ddl.auto"
value="create"/>
<property name="hibernate.transaction.manager_lookup_class"
value="org.apache.openejb.hibernate.TransactionManagerLookup" />
<property name="hibernate.cache.use_second_level_cache"
value="true" />
<property name="hibernate.cache.use_query_cache"
value="true" />
<property name="hibernate.cache.region.factory_class"
value="org.hibernate.cache.infinispan.InfinispanRegionFactory"
/>
<property name="hibernate.cache.infinispan.cfg"
value="infinispan-configs.xml" />
<property
name="hibernate.cache.infinispan.infiniSpanTest.Family.cfg"
value="family-entity"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Person.cfg"
value="person-entity"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Address.cfg"
value="address-entity"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Family.members.cfg"
value="members-collection"/>
<property
name="hibernate.cache.infinispan.infiniSpanTest.Address.inhabitants.cfg"
value="inhabitants-collection"/>
<property name="hibernate.ejb.classcache.infiniSpanTest.Family"
value="transactional" />
<property name="hibernate.ejb.classcache.infiniSpanTest.Person"
value="transactional" />
<property name="hibernate.ejb.classcache.infiniSpanTest.Address"
value="transactional" />
<property
name="hibernate.ejb.collectioncache.infiniSpanTest.Family.members"
value="transactional" />
<property
name="hibernate.ejb.collectioncache.infiniSpanTest.Address.inhabitants"
value="transactional" />
</properties>
</persistence-unit>
</persistence>
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
7:22 p.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
I don't know if this stack trace can help you, neither if it is the same root cause,
but i reproduced it at least 3 times at home. It seems that the cache named
"org.hibernate.cache.UpdateTimestampsCache" is not yet defined while the 2 nodes
are merging.
Node 1 :
01:13:44,411 Strike any key to start
01:13:51,556 InfiniSpanTest is starting its node 0/2
18 déc. 2010 01:13:51 org.apache.openejb.cdi.CdiBuilder <init>
INFO: Created new singletonService
org.apache.openejb.cdi.ThreadSingletonServiceImpl@1f8c6df
18 déc. 2010 01:13:51 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/
18-12 01:13:51,760 INFO Log4jLogStream -openejb.home =
C:\Users\Cyrille\workspace\InfiniSpanTest
18-12 01:13:51,760 INFO Log4jLogStream -openejb.base =
C:\Users\Cyrille\workspace\InfiniSpanTest
18-12 01:13:52,057 INFO Log4jLogStream -Configuring Service(id=Default Security Service,
type=SecurityService, provider-id=Default Security Service)
18-12 01:13:52,057 INFO Log4jLogStream -Configuring Service(id=Default Transaction
Manager, type=TransactionManager, provider-id=Default Transaction Manager)
18-12 01:13:52,057 INFO Log4jLogStream -Configuring Service(id=jpacacheDS, type=Resource,
provider-id=Default JDBC Database)
18-12 01:13:52,057 INFO Log4jLogStream -Configuring Service(id=My Stateless Container,
type=Container, provider-id=Default Stateless Container)
18-12 01:13:52,088 INFO Log4jLogStream -Found EjbModule in classpath:
C:\Users\Cyrille\workspace\InfiniSpanTest\target\classes
18-12 01:13:52,104 INFO Log4jLogStream -Found EjbModule in classpath:
C:\Users\Cyrille\workspace\InfiniSpanTest\target\test-classes
18-12 01:13:52,168 INFO Log4jLogStream -Beginning load:
C:\Users\Cyrille\workspace\InfiniSpanTest\target\classes
18-12 01:13:52,513 INFO Log4jLogStream -Beginning load:
C:\Users\Cyrille\workspace\InfiniSpanTest\target\test-classes
18-12 01:13:52,560 INFO Log4jLogStream -Configuring enterprise application:
classpath.ear
18-12 01:13:52,639 INFO Log4jLogStream -Configuring PersistenceUnit(name=jpacache,
provider=org.hibernate.ejb.HibernatePersistence)
18-12 01:13:52,655 INFO Log4jLogStream -Auto-creating a Resource with id
'jpacacheDSNonJta' of type 'DataSource for 'jpacache'.
18-12 01:13:52,655 INFO Log4jLogStream -Configuring Service(id=jpacacheDSNonJta,
type=Resource, provider-id=jpacacheDS)
18-12 01:13:52,655 INFO Log4jLogStream -Adjusting PersistenceUnit jpacache
<non-jta-data-source> to Resource ID 'jpacacheDSNonJta' from 'null'
18-12 01:13:52,655 INFO Log4jLogStream -Enterprise application "classpath.ear"
loaded.
18-12 01:13:52,670 INFO Log4jLogStream -Creating TransactionManager(id=Default
Transaction Manager)
18-12 01:13:52,717 INFO Log4jLogStream -Creating SecurityService(id=Default Security
Service)
18-12 01:13:52,733 INFO Log4jLogStream -Creating Resource(id=jpacacheDS)
18-12 01:13:52,748 INFO Log4jLogStream -Creating Resource(id=jpacacheDSNonJta)
18-12 01:13:52,748 INFO Log4jLogStream -Creating Container(id=My Stateless Container)
18-12 01:13:52,764 INFO Log4jLogStream -Assembling app: classpath.ear
18-12 01:13:53,826 INFO JGroupsTransport -Starting JGroups Channel
-------------------------------------------------------------------
GMS: address=ccc1-1844, cluster=infinispan-hibernate-cluster, physical
address=fe80:0:0:0:c488:ce01:b817:a808:50840
-------------------------------------------------------------------
18-12 01:13:55,154 INFO AbstractLogImpl -Received new cluster view: [ccc1-1844|0]
[ccc1-1844]
18-12 01:13:55,154 INFO AbstractLogImpl -Cache local address is ccc1-1844, physical
addresses are [fe80:0:0:0:c488:ce01:b817:a808:50840]
18-12 01:13:55,154 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,169 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,280 INFO AbstractLogImpl -Received new cluster view: [ccc1-1844|1]
[ccc1-1844, ccc1-37855]
18-12 01:13:55,295 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,311 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,311 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,326 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,389 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,593 ERROR RpcManagerImpl -unexpected error while replicating
org.infinispan.manager.NamedCacheNotFoundException: Cache:
org.hibernate.cache.UpdateTimestampsCache
at
org.infinispan.remoting.InboundInvocationHandlerImpl.handle(InboundInvocationHandlerImpl.java:72)
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)
18-12 01:13:55,593 ERROR InvocationContextInterceptor -Execution error:
org.infinispan.loaders.CacheLoaderException: org.infinispan.CacheException:
org.infinispan.manager.NamedCacheNotFoundException: Cache:
org.hibernate.cache.UpdateTimestampsCache
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:429)
Caused by: org.infinispan.CacheException:
org.infinispan.manager.NamedCacheNotFoundException: Cache:
org.hibernate.cache.UpdateTimestampsCache
at org.infinispan.remoting.rpc.RpcManagerImpl.invokeRemotely(RpcManagerImpl.java:114)
at
org.infinispan.loaders.cluster.ClusterCacheLoader.doRemoteCall(ClusterCacheLoader.java:99)
... 61 more
Caused by: org.infinispan.manager.NamedCacheNotFoundException: Cache:
org.hibernate.cache.UpdateTimestampsCache
at
org.infinispan.remoting.InboundInvocationHandlerImpl.handle(InboundInvocationHandlerImpl.java:72)
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)
18-12 01:13:55,593 INFO Log4jLogStream -PersistenceUnit(name=jpacache,
provider=org.hibernate.ejb.HibernatePersistence) - provider time 2815ms
18-12 01:13:55,593 INFO Log4jLogStream -Undeploying app: classpath.ear
18-12 01:13:55,671 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:429)
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.CacheException:
org.infinispan.manager.NamedCacheNotFoundException: Cache:
org.hibernate.cache.UpdateTimestampsCache
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.CacheException: org.infinispan.manager.NamedCacheNotFoundException: Cache:
org.hibernate.cache.UpdateTimestampsCache
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.CacheException:
org.infinispan.manager.NamedCacheNotFoundException: Cache:
org.hibernate.cache.UpdateTimestampsCache
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.CacheException:
org.infinispan.manager.NamedCacheNotFoundException: Cache:
org.hibernate.cache.UpdateTimestampsCache
at org.infinispan.remoting.rpc.RpcManagerImpl.invokeRemotely(RpcManagerImpl.java:114)
at
org.infinispan.loaders.cluster.ClusterCacheLoader.doRemoteCall(ClusterCacheLoader.java:99)
... 61 more
Caused by: org.infinispan.manager.NamedCacheNotFoundException: Cache:
org.hibernate.cache.UpdateTimestampsCache
at
org.infinispan.remoting.InboundInvocationHandlerImpl.handle(InboundInvocationHandlerImpl.java:72)
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)
01:13:55,671 Test of node 0/2 will begin now
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:437)
01:13:55,686 InfiniSpanTest is stopping its node 0/2
18-12 01:13:55,686 INFO Log4jLogStream -Destroying container system
18-12 01:13:55,686 INFO Log4jLogStream -Closing DataSource: jpacacheDS
18-12 01:13:55,686 INFO Log4jLogStream -Closing DataSource: jpacacheDSNonJta
18-12 01:14:10,585 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@9e0f3801] for requestor
[Thread[Incoming-2,ccc1-1844,5,Thread Pools]]! Lock held by [Thread[main,5,]]
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)
Node 2 :
01:13:45,768 Strike any key to start
01:13:52,135 InfiniSpanTest is starting its node 1/2
18 déc. 2010 01:13:52 org.apache.openejb.cdi.CdiBuilder <init>
INFO: Created new singletonService
org.apache.openejb.cdi.ThreadSingletonServiceImpl@1c86be5
Apache OpenEJB 3.2-SNAPSHOT build: 20101203-01:57
http://openejb.apache.org/
18-12 01:13:52,309 INFO Log4jLogStream -openejb.home =
C:\Users\Cyrille\workspace\InfiniSpanTest
18-12 01:13:52,324 INFO Log4jLogStream -openejb.base =
C:\Users\Cyrille\workspace\InfiniSpanTest
18 déc. 2010 01:13:52 org.apache.openejb.cdi.CdiBuilder <init>
INFO: succeeded in installing singleton service
18-12 01:13:52,624 INFO Log4jLogStream -Configuring Service(id=Default Security Service,
type=SecurityService, provider-id=Default Security Service)
18-12 01:13:52,624 INFO Log4jLogStream -Configuring Service(id=Default Transaction
Manager, type=TransactionManager, provider-id=Default Transaction Manager)
18-12 01:13:52,624 INFO Log4jLogStream -Configuring Service(id=jpacacheDS, type=Resource,
provider-id=Default JDBC Database)
18-12 01:13:52,624 INFO Log4jLogStream -Configuring Service(id=My Stateless Container,
type=Container, provider-id=Default Stateless Container)
18-12 01:13:52,655 INFO Log4jLogStream -Found EjbModule in classpath:
C:\Users\Cyrille\workspace\InfiniSpanTest\target\classes
18-12 01:13:52,655 INFO Log4jLogStream -Found EjbModule in classpath:
C:\Users\Cyrille\workspace\InfiniSpanTest\target\test-classes
18-12 01:13:52,733 INFO Log4jLogStream -Beginning load:
C:\Users\Cyrille\workspace\InfiniSpanTest\target\classes
18-12 01:13:53,076 INFO Log4jLogStream -Beginning load:
C:\Users\Cyrille\workspace\InfiniSpanTest\target\test-classes
18-12 01:13:53,107 INFO Log4jLogStream -Configuring enterprise application:
classpath.ear
18-12 01:13:53,201 INFO Log4jLogStream -Configuring PersistenceUnit(name=jpacache,
provider=org.hibernate.ejb.HibernatePersistence)
18-12 01:13:53,201 INFO Log4jLogStream -Auto-creating a Resource with id
'jpacacheDSNonJta' of type 'DataSource for 'jpacache'.
18-12 01:13:53,201 INFO Log4jLogStream -Configuring Service(id=jpacacheDSNonJta,
type=Resource, provider-id=jpacacheDS)
18-12 01:13:53,201 INFO Log4jLogStream -Adjusting PersistenceUnit jpacache
<non-jta-data-source> to Resource ID 'jpacacheDSNonJta' from 'null'
18-12 01:13:53,216 INFO Log4jLogStream -Enterprise application "classpath.ear"
loaded.
18-12 01:13:53,216 INFO Log4jLogStream -Creating TransactionManager(id=Default
Transaction Manager)
18-12 01:13:53,280 INFO Log4jLogStream -Creating SecurityService(id=Default Security
Service)
18-12 01:13:53,295 INFO Log4jLogStream -Creating Resource(id=jpacacheDS)
18-12 01:13:53,311 INFO Log4jLogStream -Creating Resource(id=jpacacheDSNonJta)
18-12 01:13:53,311 INFO Log4jLogStream -Creating Container(id=My Stateless Container)
18-12 01:13:53,327 INFO Log4jLogStream -Assembling app: classpath.ear
18-12 01:13:54,373 INFO JGroupsTransport -Starting JGroups Channel
-------------------------------------------------------------------
GMS: address=ccc1-37855, cluster=infinispan-hibernate-cluster, physical
address=fe80:0:0:0:c488:ce01:b817:a808:50841
-------------------------------------------------------------------
18-12 01:13:55,295 INFO AbstractLogImpl -Received new cluster view: [ccc1-1844|1]
[ccc1-1844, ccc1-37855]
18-12 01:13:55,326 INFO AbstractLogImpl -Cache local address is ccc1-37855, physical
addresses are [fe80:0:0:0:c488:ce01:b817:a808:50841]
18-12 01:13:55,326 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,326 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,436 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,451 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,451 ERROR AbstractLogImpl -Defined caches: [timestamps, entity-repeatable,
entity, local-query, replicated-query, infiniSpanTest.Family, family-entity,
person-entity, infiniSpanTest.Person, replicated-entity, infiniSpanTest.Address,
address-entity]
18-12 01:13:55,451 INFO AbstractLogImpl -Cache org.hibernate.cache.UpdateTimestampsCache
is not defined. No point in waiting.
18-12 01:13:55,451 INFO AbstractLogImpl -Cache named
org.hibernate.cache.UpdateTimestampsCache does not exist on this cache manager!
18-12 01:13:55,451 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,468 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:13:55,530 INFO AbstractComponentRegistry -Infinispan version: Infinispan
'Ursus' 4.2.0-SNAPSHOT
18-12 01:14:10,695 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@9e0f3801] for requestor
[Thread[Incoming-2,ccc1-1844,5,Thread Pools]]! Lock held by [Thread[main,5,]]
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:429)
Caused by: org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after
[15 seconds] on key [org.hibernate.cache.infinispan.util.CacheHelper$EvictAll@9e0f3801]
for requestor [Thread[Incoming-2,ccc1-1844,5,Thread Pools]]! Lock held by
[Thread[main,5,]]
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)
18-12 01:14:10,711 INFO Log4jLogStream -PersistenceUnit(name=jpacache,
provider=org.hibernate.ejb.HibernatePersistence) - provider time 17367ms
18-12 01:14:10,711 INFO Log4jLogStream -Undeploying app: classpath.ear
18-12 01:14:10,774 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:429)
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@9e0f3801] for requestor
[Thread[Incoming-2,ccc1-1844,5,Thread Pools]]! Lock held by [Thread[main,5,]]
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@9e0f3801] for requestor
[Thread[Incoming-2,ccc1-1844,5,Thread Pools]]! Lock held by [Thread[main,5,]]
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@9e0f3801] for requestor
[Thread[Incoming-2,ccc1-1844,5,Thread Pools]]! Lock held by [Thread[main,5,]]
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@9e0f3801]
for requestor [Thread[Incoming-2,ccc1-1844,5,Thread Pools]]! Lock held by
[Thread[main,5,]]
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)
01:14:10,774 Test of node 1/2 will begin now
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:437)
01:14:10,790 InfiniSpanTest is stopping its node 1/2
18-12 01:14:10,790 INFO Log4jLogStream -Destroying container system
18-12 01:14:10,790 INFO Log4jLogStream -Closing DataSource: jpacacheDS
18-12 01:14:10,790 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
4:16 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
Hmmm, it seems like evictAll() calls that modify one of the caches are resulting in
unnecessary remote get calls. Since we don't need previous values, this could be
skipped simply with Flag.SKIP_CACHE_LOAD. If it all looks good, I'll add this change
and you can pick it from the Hibernate code as before.
Cyrille, for any further comments, please open up a user forum post in
http://community.jboss.org/en/infinispan?view=discussions
User forums are a much better place to discuss this and avoid polluting JIRA issues.
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
5:15 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Galder Zamarreño commented on ISPN-832:
---------------------------------------
Right, the change is in now
(https://github.com/hibernate/hibernate-core/commit/35eb737e21f527d69b7ed6...)
As mentioned earlier, if you carry on having issues, please open a user forum post and
we'll follow there.
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
6:57 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
I still have TimeoutException.
As you asked i have created a discussion on the forum
(http://community.jboss.org/thread/160705).
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
7:20 a.m.
New subject: [JBoss JIRA] Commented: (ISPN-832) TimeoutException with 2 or more nodes when there are Entity inserts, updates or removes.
[
https://issues.jboss.org/browse/ISPN-832?page=com.atlassian.jira.plugin.s...
]
Cyrille Charron commented on ISPN-832:
--------------------------------------
All is OK... there's no more TimeoutException by using the last snapshot AND by using
TCP stacks for jgroups.
Thanks for all.
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
4845
days inactive
4879
days old
infinispan-issues@lists.jboss.org
29 comments
3 participants
participants (3)
-
Cyrille Charron (JIRA) -
Galder Zamarreño (JIRA)
-
Manik Surtani (JIRA)