[ https://jira.jboss.org/jira/browse/JBAS-7434?page=com.atlassian.jira.plug... ] Brian Stansberry commented on JBAS-7434: ---------------------------------------- These tests were just moved out of the ejb3 project and into the AS testsuite (JBAS-7428). So this is the first time they've been run with JTS; not a regression. -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: https://jira.jboss.org/jira/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ https://jira.jboss.org/jira/browse/JBAS-7434?page=com.atlassian.jira.plug... ] Brian Stansberry commented on JBAS-7434: ---------------------------------------- Appears to be a classloading issue. Note the switch in the thread name in the middle of the logging. This is an entity insert at the beginning of the test: 2009-11-12 15:44:17,139 DEBUG [org.jboss.ejb3.stateful.StatefulContainer] (WorkerThread#0[127.0.0.1:64635]) Received dynamic invocation for method with hash: -792204721456858463 2009-11-12 15:44:17,142 DEBUG [org.jboss.ejb3.entity.ExtendedPersistenceContextPropagationInterceptor] (WorkerThread#0[127.0.0.1:64635]) ++++ LongLivedSessionPropagationInterceptor 2009-11-12 15:44:17,142 DEBUG [org.hibernate.impl.SessionImpl] (WorkerThread#0[127.0.0.1:64635]) opened session at timestamp: 12580118571 2009-11-12 15:44:17,142 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] (WorkerThread#0[127.0.0.1:64635]) Looking for a JTA transaction to join 2009-11-12 15:44:17,143 DEBUG [org.hibernate.jdbc.JDBCContext] (WorkerThread#0[127.0.0.1:64635]) successfully registered Synchronization 2009-11-12 15:44:17,144 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] (WorkerThread#0[127.0.0.1:64635]) Looking for a JTA transaction to join 2009-11-12 15:44:17,144 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] (WorkerThread#0[127.0.0.1:64635]) Transaction already joined 2009-11-12 15:44:17,162 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] (WorkerThread#0[127.0.0.1:64635]) generated identifier: 1001, using strategy: org.hibernate.id.Assigned 2009-11-12 15:44:17,185 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] (RequestProcessor-2) processing flush-time cascades 2009-11-12 15:44:17,186 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] (RequestProcessor-2) dirty checking collections 2009-11-12 15:44:17,189 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] (RequestProcessor-2) Flushed: 1 insertions, 0 updates, 0 deletions to 1 objects 2009-11-12 15:44:17,189 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] (RequestProcessor-2) Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections 2009-11-12 15:44:17,190 DEBUG [org.hibernate.pretty.Printer] (RequestProcessor-2) listing entities: 2009-11-12 15:44:17,190 DEBUG [org.hibernate.pretty.Printer] (RequestProcessor-2) org.jboss.test.cluster.clusteredentity.classloader.Account{id=1001, balance=5, accountHolder=2c6d8085f3f280c2eff2e7aeeae2eff3f3aef4e5f3f4aee3ecf5f3f4e5f2aee3ecf5f3f4e5f2e5e4e5eef4e9f4f9aee3ece1f3f3ecefe1e4e5f2aec1e3e3eff5eef4c8efece4e5f2d0cb8080808080808081828082cc8088ece1f3f4cee1ede5f48092cceae1f6e1afece1eee7afd3f4f2e9eee7bbcc8083f3f3eef180fe8081f8f0f48085d3ede9f4e8f48084b1b0b0b0, branch=94536} 2009-11-12 15:44:17,190 DEBUG [org.hibernate.cache.UpdateTimestampsCache] (RequestProcessor-2) Pre-invalidating space [Account] 2009-11-12 15:44:17,198 DEBUG [org.hibernate.jdbc.AbstractBatcher] (RequestProcessor-2) about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2009-11-12 15:44:17,198 DEBUG [org.hibernate.jdbc.ConnectionManager] (RequestProcessor-2) opening JDBC connection 2009-11-12 15:44:17,205 DEBUG [org.hibernate.SQL] (RequestProcessor-2) insert into Account (accountHolder, balance, branch, id) values (?, ?, ?, ?) 2009-11-12 15:44:17,209 ERROR [org.hibernate.event.def.AbstractFlushingEventListener] (RequestProcessor-2) Could not synchronize database state with session: org.hibernate.type.SerializationException: could not deserialize at org.hibernate.util.SerializationHelper.deserialize(SerializationHelper.java:185) at org.hibernate.util.SerializationHelper.deserialize(SerializationHelper.java:211) at org.hibernate.type.SerializableType.fromBytes(SerializableType.java:105) at org.hibernate.type.SerializableType.deepCopyNotNull(SerializableType.java:97) at org.hibernate.type.MutableType.deepCopy(MutableType.java:48) at org.hibernate.type.SerializableToBlobType.deepCopy(SerializableToBlobType.java:102) at org.hibernate.type.AbstractType.disassemble(AbstractType.java:78) at org.hibernate.type.TypeFactory.disassemble(TypeFactory.java:474) at org.hibernate.cache.entry.CacheEntry.<init>(CacheEntry.java:69) at org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:102) at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279) at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263) at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:167) at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321) at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:50) at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1027) at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:365) at org.hibernate.ejb.AbstractEntityManagerImpl$1.beforeCompletion(AbstractEntityManagerImpl.java:504) at com.arjuna.ats.internal.jta.resources.jts.orbspecific.SynchronizationImple.before_completion(SynchronizationImple.java:118) at com.arjuna.ArjunaOTS.ManagedSynchronizationPOATie.before_completion(ManagedSynchronizationPOATie.java:53) at com.arjuna.ArjunaOTS.ManagedSynchronizationPOA._invoke(ManagedSynchronizationPOA.java:46) at org.jacorb.poa.RequestProcessor.invokeOperation(Unknown Source) at org.jacorb.poa.RequestProcessor.process(Unknown Source) at org.jacorb.poa.RequestProcessor.run(Unknown Source) Caused by: java.lang.ClassNotFoundException: org.jboss.test.cluster.clusteredentity.classloader.AccountHolderPK at java.net.URLClassLoader$1.run(URLClassLoader.java:200) at java.security.AccessController.doPrivileged(Native Method) at java.net.URLClassLoader.findClass(URLClassLoader.java:188) at java.lang.ClassLoader.loadClass(ClassLoader.java:319) at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:330) at java.lang.ClassLoader.loadClass(ClassLoader.java:254) at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:399) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Class.java:247) at org.jboss.classloader.spi.base.BaseClassLoaderDomain.loadClass(BaseClassLoaderDomain.java:292) at org.jboss.classloader.spi.base.BaseClassLoaderDomain.loadClass(BaseClassLoaderDomain.java:1119) at org.jboss.classloader.spi.base.BaseClassLoader.loadClassFromDomain(BaseClassLoader.java:798) at org.jboss.classloader.spi.base.BaseClassLoader.loadClass(BaseClassLoader.java:441) at java.lang.ClassLoader.loadClass(ClassLoader.java:254) at java.lang.ClassLoader.loadClassInternal(ClassLoader.java:399) at java.lang.Class.forName0(Native Method) at java.lang.Class.forName(Class.java:247) at java.io.ObjectInputStream.resolveClass(ObjectInputStream.java:604) at org.hibernate.util.SerializationHelper$CustomObjectInputStream.resolveClass(SerializationHelper.java:239) at java.io.ObjectInputStream.readNonProxyDesc(ObjectInputStream.java:1575) at java.io.ObjectInputStream.readClassDesc(ObjectInputStream.java:1496) at java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:1732) at java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1329) at java.io.ObjectInputStream.readObject(ObjectInputStream.java:351) at org.hibernate.util.SerializationHelper.deserialize(SerializationHelper.java:181) ... 23 more 2009-11-12 15:44:17,212 DEBUG [org.hibernate.jdbc.ConnectionManager] (RequestProcessor-2) aggressively releasing JDBC connection 2009-11-12 15:44:17,212 DEBUG [org.hibernate.jdbc.ConnectionManager] (RequestProcessor-2) releasing JDBC connection [ (open PreparedStatements: 1, globally: 1) (open ResultSets: 0, globally: 0)] 2009-11-12 15:44:17,213 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] (RequestProcessor-2) mark transaction for rollback 2009-11-12 15:44:17,217 WARN [com.arjuna.ats.jts.logging.loggerI18N] (WorkerThread#0[127.0.0.1:64635]) [com.arjuna.ats.internal.jts.orbspecific.coordinator.generror] ArjunaTransactionImple.doBeforeCompletion caught exception: org.omg.CORBA.UNKNOWN: Server-side Exception: vmcid: 0x0 minor code: 0 completed: No 2009-11-12 15:44:17,223 TRACE [org.jboss.cache.interceptors.TxInterceptor] (RequestProcessor-2) Running rollback phase 2009-11-12 15:44:17,224 TRACE [org.jboss.cache.interceptors.TxInterceptor] (RequestProcessor-2) Finished rollback phase 2009-11-12 15:44:17,225 DEBUG [org.jboss.jpa.deployment.ManagedEntityManagerFactory] (RequestProcessor-2) ************** closing entity managersession ************** 2009-11-12 15:44:17,226 DEBUG [org.hibernate.cache.UpdateTimestampsCache] (RequestProcessor-2) Invalidating space [Account], timestamp: 12580118572 -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: https://jira.jboss.org/jira/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ https://jira.jboss.org/jira/browse/JBAS-7434?page=com.atlassian.jira.plug... ] Brian Stansberry commented on JBAS-7434: ---------------------------------------- Created a separate test for this, org.jboss.test.jpa.test.ScopedWithCacheUnitTestCase that fails with JTS, ok with the standard setup. Failure stack trace is as above. The test simply: 1) deploys and ear with an isolated classloader including 2) deploy a persistence unit w/ 2LC enabled (HashtableCacheProvider) 3) invokes on an SLSB which, inside a tx, tries to 4) insert an entity w/ an @Lob field In 2) the second level cache is relevant because stack trace above shows failure happening during disassembly of an entity for storage in the cache. In 3) I use the SLSB just to a) provide a remote interface for the test driver and b) mark transaction demarcation trivial. The thread that fails is an org.jacorb.poa.RequestProcessor. Whatever is submitting a task to that thread is either not setting the tcl (JTS bug) or whatever synchronization etc it calls into is supposed to set the tcl and isn't (hibernate bug). -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: https://jira.jboss.org/jira/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ https://jira.jboss.org/jira/browse/JBAS-7434?page=com.atlassian.jira.plug... ] Brian Stansberry updated JBAS-7434: ----------------------------------- Fix Version/s: JBossAS-6.0.0.M2 (was: JBossAS-6.0.0.M1) JBoss Forum Reference: http://www.jboss.org/index.html?module=bb&op=viewforum&f=240 Affects Version/s: JBossAS-6.0.0.M1 Assignee: Steve Ebersole (was: Brian Stansberry) Steve, as discussed on IRC, I'm passing this to you as it's an issue of how Hibernate interacts with a transaction manager. But I first opened a thread on the JBossTS forum raising the issue, as it seems like a generic one -- transaction Synchronization needing to be able to load classes not visible to the TM's classloader. Perhaps the JTS guys will take it over. [1] http://www.jboss.org/index.html?module=bb&op=viewforum&f=240 -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: https://jira.jboss.org/jira/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ https://jira.jboss.org/jira/browse/JBAS-7434?page=com.atlassian.jira.plug... ] Brian Stansberry updated JBAS-7434: ----------------------------------- JBoss Forum Reference: http://www.jboss.org/index.html?module=bb&op=viewtopic&t=163861 (was: http://www.jboss.org/index.html?module=bb&op=viewforum&f=240) -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: https://jira.jboss.org/jira/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ https://jira.jboss.org/jira/browse/JBAS-7434?page=com.atlassian.jira.plug... ] Brian Stansberry updated JBAS-7434: ----------------------------------- Fix Version/s: JBossAS-6.0.0.M3 (was: JBossAS-6.0.0.M2) -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: https://jira.jboss.org/jira/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ https://jira.jboss.org/jira/browse/JBAS-7434?page=com.atlassian.jira.plug... ] Steve Ebersole commented on JBAS-7434: -------------------------------------- Which version of Hibernate is this? 3.5? -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: https://jira.jboss.org/jira/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ https://jira.jboss.org/jira/browse/JBAS-7434?page=com.atlassian.jira.plug... ] Steve Ebersole commented on JBAS-7434: -------------------------------------- 1) <version.org.hibernate> and <version.org.hibernate.entity.manager> can be combined now... They should both be 3.5.1 (3.5.2 is next wed 5/12) 2) <version.org.hibernate.commons.annotations> should be 3.2.0.Final 3) <version.org.hibernate.javax.persistence> should be 1.0.0.Final (provided you are using org.hibernate.javax.persistence:hibernate-jpa-2.0-api) 4) <version.org.hibernate.validator> not sure -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: https://jira.jboss.org/jira/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira