[ http://opensource.atlassian.com/projects/hibernate/browse/HHH-2052?page=c... ] Francis commented on HHH-2052: ------------------------------ This happens on 3.2.3, and happens when using a Bag/OneToMany with a reference to <properties> on both sides (A composite FK is used for the join), of course only when tracing is enabled. I think the offending code is here (in MessageHelper): public static String collectionInfoString( CollectionPersister persister, Serializable id, SessionFactoryImplementor factory) { StringBuffer s = new StringBuffer(); s.append( '[' ); if ( persister == null ) { s.append( "<unreferenced>" ); } else { s.append( persister.getRole() ); s.append( '#' ); if ( id == null ) { s.append( "<null>" ); } else { // Need to use the identifier type of the collection owner // since the incoming is value is actually the owner's id. // Using the collection's key type causes problems with // property-ref keys... } } s.append( ']' ); return s.toString(); Isn't it funny the frequency that there are problems with code that requires an extensive comment? Sorry I don't know the internals well enough to come up with a patch. Thread [main] (Suspended (exception ClassCastException)) StringType.toString(Object) line: 44 StringType(NullableType).toLoggableString(Object, SessionFactoryImplementor) line: 218 MessageHelper.collectionInfoString(CollectionPersister, Serializable, SessionFactoryImplementor) line: 284 OneToManyLoader(Loader).loadCollection(SessionImplementor, Serializable, Type) line: 1986 OneToManyLoader(CollectionLoader).initialize(Serializable, SessionImplementor) line: 36 OneToManyPersister(AbstractCollectionPersister).initialize(Serializable, SessionImplementor) line: 565 DefaultInitializeCollectionEventListener.onInitializeCollection(InitializeCollectionEvent) line: 60 SessionImpl.initializeCollection(PersistentCollection, boolean) line: 1716 PersistentBag(AbstractPersistentCollection).forceInitialization() line: 454 StatefulPersistenceContext.initializeNonLazyCollections() line: 785 QueryLoader(Loader).loadSingleRow(ResultSet, SessionImplementor, QueryParameters, boolean) line: 294 ScrollableResultsImpl.prepareCurrentRow(boolean) line: 231 ScrollableResultsImpl.next() line: 100 DatabaseReader.parse(InputSource) line: 173 ... -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: http://opensource.atlassian.com/projects/hibernate/secure/Administrators.... - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ http://opensource.atlassian.com/projects/hibernate/browse/HHH-2052?page=c... ] Peter Wyngaard commented on HHH-2052: ------------------------------------- Me too, I've run into this bug. In my case, it's trying to create the DEBUG message: DEBUG org.hibernate.engine.CollectionLoadContext - creating collection wrapper:[my.class.name#myId] The collection it is trying to wrap is a ManyToMany association. Here is the annotation on the getter: @ManyToMany(cascade = { CascadeType.ALL }, fetch = FetchType.LAZY) @JoinTable( name="STUDY_PARAMETER_ASSIGN", joinColumns={@JoinColumn(name="STUDY_ID",referencedColumnName="STUDY_ID")}, inverseJoinColumns={@JoinColumn(name="PARAMETER_VALUE_ID")} ) Note the "referencedColumnName" in the JoinColumns. I have to include this because the referenced column is not the primary key in the referenced table (due to circumstances beyond our control...) There is another class in the application that also has a ManyToMany association, but it works just fine. Here is the annotation in that class: @ManyToMany(cascade = { CascadeType.ALL }, fetch = FetchType.LAZY) @JoinTable( name="SAMPLE_PARAMETER_ASSIGN", joinColumns={@JoinColumn(name="SAMPLE_ID")}, inverseJoinColumns={@JoinColumn(name="PARAMETER_VALUE_ID")} ) Note that this class does not have the "referencedColumnName" annotation. I'm employing the same workaround. I have put the following in my log4j.properties: log4j.logger.org.hibernate.engine.CollectionLoadContext=INFO -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: http://opensource.atlassian.com/projects/hibernate/secure/Administrators.... - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ http://opensource.atlassian.com/projects/hibernate/browse/HHH-2052?page=c... ] Ryan J Sullivan commented on HHH-2052: -------------------------------------- I'm seeing this error in 3.5cr2 and I'm finding it very annoying. -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: http://opensource.atlassian.com/projects/hibernate/secure/Administrators.... - For more information on JIRA, see: http://www.atlassian.com/software/jira

[ http://opensource.atlassian.com/projects/hibernate/browse/HHH-2052?page=c... ] Prem commented on HHH-2052: --------------------------- CalssCastException HBM snippet: <class name="com.test.ReportApp" table="INV_REPAPP"> <composite-id mapped="false" unsaved-value="undefined"> <key-property name="reportid" type="int"> <column name="REPORTID"/> </key-property> </composite-id> <property generated="never" lazy="false" name="appgroupid" type="string"> <column length="60" name="APPGROUPID"/> </property> <property generated="never" lazy="false" name="appid" type="string"> <column length="60" name="APPID"/> </property> <property generated="never" lazy="false" name="reportno" type="string"> <column length="30" name="REPORTNO"/> </property> <property generated="never" lazy="false" name="reportname" type="string"> <column length="100" name="REPORTNAME"/> </property> <property generated="never" lazy="false" name="recordclasscode" type="string"> <column length="6" name="RECORDCLASSCODE"/> </property> <property generated="never" lazy="false" name="folderid" type="string"> <column length="60" name="FOLDERID"/> </property> <property generated="never" lazy="false" name="inventoryId" type="int"> <column name="INVENTORY_ID" unique="true"/> </property> <property generated="never" lazy="false" name="expirationtype" type="string"> <column length="16" name="EXPIRATIONTYPE"/> </property> <set name="ilmFields" table="INV_REPILM" > <key column="INVENTORY_ID" property-ref="inventoryId" update="false" /> <one-to-many entity-name="com.test.Repilm" not-found="exception" /> </set> </class> ------------------------------------- Error: org.hibernate.PropertyAccessException: Exception occurred inside setter of com.test.ReportApp.ilmFields at org.hibernate.property.BasicPropertyAccessor$BasicSetter.set(BasicPropertyAccessor.java:89) [hibernate3.jar:na] at org.hibernate.tuple.entity.AbstractEntityTuplizer.setPropertyValues(AbstractEntityTuplizer.java:352) [hibernate3.jar:na] at org.hibernate.tuple.entity.PojoEntityTuplizer.setPropertyValues(PojoEntityTuplizer.java:232) [hibernate3.jar:na] at org.hibernate.persister.entity.AbstractEntityPersister.setPropertyValues(AbstractEntityPersister.java:3580) [hibernate3.jar:na] at org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:152) [hibernate3.jar:na] at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:877) [hibernate3.jar:na] at org.hibernate.loader.Loader.doQuery(Loader.java:752) [hibernate3.jar:na] at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:259) [hibernate3.jar:na] at org.hibernate.loader.Loader.loadEntity(Loader.java:1885) [hibernate3.jar:na] at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:71) [hibernate3.jar:na] at org.hibernate.loader.entity.AbstractEntityLoader.load(AbstractEntityLoader.java:65) [hibernate3.jar:na] at org.hibernate.loader.entity.BatchingEntityLoader.load(BatchingEntityLoader.java:105) [hibernate3.jar:na] at org.hibernate.persister.entity.AbstractEntityPersister.load(AbstractEntityPersister.java:3062) [hibernate3.jar:na] at org.hibernate.event.def.DefaultLoadEventListener.loadFromDatasource(DefaultLoadEventListener.java:434) [hibernate3.jar:na] at org.hibernate.event.def.DefaultLoadEventListener.doLoad(DefaultLoadEventListener.java:415) [hibernate3.jar:na] at org.hibernate.event.def.DefaultLoadEventListener.load(DefaultLoadEventListener.java:165) [hibernate3.jar:na] at org.hibernate.event.def.DefaultLoadEventListener.proxyOrLoad(DefaultLoadEventListener.java:223) [hibernate3.jar:na] at org.hibernate.event.def.DefaultLoadEventListener.onLoad(DefaultLoadEventListener.java:126) [hibernate3.jar:na] at org.hibernate.impl.SessionImpl.fireLoad(SessionImpl.java:906) [hibernate3.jar:na] at org.hibernate.impl.SessionImpl.internalLoad(SessionImpl.java:874) [hibernate3.jar:na] at org.hibernate.type.EntityType.resolveIdentifier(EntityType.java:590) [hibernate3.jar:na] at org.hibernate.type.EntityType.resolve(EntityType.java:412) [hibernate3.jar:na] at org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:139) [hibernate3.jar:na] at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:877) [hibernate3.jar:na] at org.hibernate.loader.Loader.doQuery(Loader.java:752) [hibernate3.jar:na] at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:259) [hibernate3.jar:na] at org.hibernate.loader.Loader.doList(Loader.java:2232) [hibernate3.jar:na] at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2129) [hibernate3.jar:na] at org.hibernate.loader.Loader.list(Loader.java:2124) [hibernate3.jar:na] at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:401) [hibernate3.jar:na] at org.hibernate.hql.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:363) [hibernate3.jar:na] at org.hibernate.engine.query.HQLQueryPlan.performList(HQLQueryPlan.java:196) [hibernate3.jar:na] at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1149) [hibernate3.jar:na] at org.hibernate.impl.QueryImpl.list(QueryImpl.java:102) [hibernate3.jar:na] at org.springframework.orm.hibernate3.HibernateTemplate$34.doInHibernate(HibernateTemplate.java:1015) [org.springframework.orm.jar:3.0.0.RC1] at org.springframework.orm.hibernate3.HibernateTemplate$34.doInHibernate(HibernateTemplate.java:1) [org.springframework.orm.jar:3.0.0.RC1] at org.springframework.orm.hibernate3.HibernateTemplate.doExecute(HibernateTemplate.java:406) [org.springframework.orm.jar:3.0.0.RC1] at org.springframework.orm.hibernate3.HibernateTemplate.executeWithNativeSession(HibernateTemplate.java:374) [org.springframework.orm.jar:3.0.0.RC1] at org.springframework.orm.hibernate3.HibernateTemplate.findByNamedQueryAndNamedParam(HibernateTemplate.java:1006) [org.springframework.orm.jar:3.0.0.RC1] at com.msdw.ondemand.dao.hibernate.archivedw.ReportDaoImpl.getReportById(ReportDaoImpl.java:39) [build/:na] at com.msdw.ondemand.dao.archivedw.ReportDaoTest.getReport(ReportDaoTest.java:49) [build/:na] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [na:1.6.0_27] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [na:1.6.0_27] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.6.0_27] at java.lang.reflect.Method.invoke(Method.java:597) [na:1.6.0_27] at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44) [junit.jar:na] at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) [junit.jar:na] at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41) [junit.jar:na] at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) [junit.jar:na] at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) [junit.jar:na] at org.springframework.test.context.junit4.statements.RunBeforeTestMethodCallbacks.evaluate(RunBeforeTestMethodCallbacks.java:74) [org.springframework.test.jar:3.0.0.RC1] at org.springframework.test.context.junit4.statements.RunAfterTestMethodCallbacks.evaluate(RunAfterTestMethodCallbacks.java:82) [org.springframework.test.jar:3.0.0.RC1] at org.springframework.test.context.junit4.statements.SpringRepeat.evaluate(SpringRepeat.java:72) [org.springframework.test.jar:3.0.0.RC1] at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.runChild(SpringJUnit4ClassRunner.java:240) [org.springframework.test.jar:3.0.0.RC1] at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:50) [junit.jar:na] at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193) [junit.jar:na] at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52) [junit.jar:na] at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191) [junit.jar:na] at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42) [junit.jar:na] at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184) [junit.jar:na] at org.springframework.test.context.junit4.statements.RunBeforeTestClassCallbacks.evaluate(RunBeforeTestClassCallbacks.java:61) [org.springframework.test.jar:3.0.0.RC1] at org.springframework.test.context.junit4.statements.RunAfterTestClassCallbacks.evaluate(RunAfterTestClassCallbacks.java:70) [org.springframework.test.jar:3.0.0.RC1] at org.junit.runners.ParentRunner.run(ParentRunner.java:236) [junit.jar:na] at org.springframework.test.context.junit4.SpringJUnit4ClassRunner.run(SpringJUnit4ClassRunner.java:180) [org.springframework.test.jar:3.0.0.RC1] at org.eclipse.jdt.internal.junit4.runner.JUnit4TestReference.run(JUnit4TestReference.java:50) [.cp/:na] at org.eclipse.jdt.internal.junit.runner.TestExecution.run(TestExecution.java:38) [.cp/:na] at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:467) [.cp/:na] at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.runTests(RemoteTestRunner.java:683) [.cp/:na] at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.run(RemoteTestRunner.java:390) [.cp/:na] at org.eclipse.jdt.internal.junit.runner.RemoteTestRunner.main(RemoteTestRunner.java:197) [.cp/:na] Caused by: java.lang.reflect.InvocationTargetException: null at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [na:1.6.0_27] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [na:1.6.0_27] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [na:1.6.0_27] at java.lang.reflect.Method.invoke(Method.java:597) [na:1.6.0_27] at org.hibernate.property.BasicPropertyAccessor$BasicSetter.set(BasicPropertyAccessor.java:66) [hibernate3.jar:na] ... 69 common frames omitted Caused by: java.lang.ClassCastException: java.lang.Integer at org.hibernate.type.ComponentType.toLoggableString(ComponentType.java:400) [hibernate3.jar:na] at org.hibernate.pretty.MessageHelper.collectionInfoString(MessageHelper.java:307) [hibernate3.jar:na] at org.hibernate.loader.Loader.loadCollection(Loader.java:2011) [hibernate3.jar:na] at org.hibernate.loader.collection.BatchingCollectionInitializer.initialize(BatchingCollectionInitializer.java:75) [hibernate3.jar:na] at org.hibernate.persister.collection.AbstractCollectionPersister.initialize(AbstractCollectionPersister.java:587) [hibernate3.jar:na] at org.hibernate.event.def.DefaultInitializeCollectionEventListener.onInitializeCollection(DefaultInitializeCollectionEventListener.java:83) [hibernate3.jar:na] at org.hibernate.impl.SessionImpl.initializeCollection(SessionImpl.java:1744) [hibernate3.jar:na] at org.hibernate.collection.AbstractPersistentCollection.initialize(AbstractPersistentCollection.java:366) [hibernate3.jar:na] at org.hibernate.collection.AbstractPersistentCollection.read(AbstractPersistentCollection.java:108) [hibernate3.jar:na] at org.hibernate.collection.AbstractPersistentCollection.readSize(AbstractPersistentCollection.java:131) [hibernate3.jar:na] at org.hibernate.collection.PersistentSet.size(PersistentSet.java:162) [hibernate3.jar:na] at com.test.ReportApp.setIlmFields(ReportApp.java:43) [build/:na] ... 74 common frames omitted -- This message is automatically generated by JIRA. For more information on JIRA, see: http://www.atlassian.com/software/jira