[jboss-jira] [JBoss JIRA] Commented: (JBAS-7434) org.jboss.test.cluster.defaultcfg.clusteredentity

[ 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