-->
These old forums are deprecated now and set to read-only. We are waiting for you on our new forums!
More modern, Discourse-based and with GitHub/Google/Twitter authentication built-in.

View unanswered posts | View active topics


Board index » Hibernate & Java Persistence » Hibernate Users

All times are UTC - 5 hours [ DST ]


Forum locked This topic is locked, you cannot edit posts or make further replies.  Page 1 of 1
  [ 2 posts ] 
  Previous topic | Next topic 
Author Message
mpopetz
 Post subject: hibernate.debug causing ConcurrentModificationException
PostPosted: Wed Apr 13, 2005 2:22 pm 
Beginner
Beginner

Joined: Tue Oct 28, 2003 12:09 pm
Posts: 46
I'm doing a large cascading delete and it fails with the following exception ONLY when hibernate debugging is turned on.

log4j.logger.net.sf.hibernate=debug

I'm not entirely sure I have all my cascades set up properly (that's what I'm checking on with this testing) but it shouldn't happen in any case? No?

It appears to be trying to print an object of a collection to the log that causes the exception...so maybe a cyclical cascade in my code? I'll track that down, but it seems odd that a cascade that works without debugging should fail with debugging enabled. :P

Perhaps it's hydrating objects to print them when it woudln't have hydrated them if it weren't trying to print them?

Thoughts? Anyone seen this before so I can track down the cyclical cascades assuming it exists?

I have LOTS of mapping files (25+) and this is a top-of-the-graph object I'm deleting so I didn't want to include them all for fear of overwhelming folks.

-mp


Hibernate version:

2.1.8


Full stack trace of any exception that occurs:

Code:
java.util.ConcurrentModificationException
   at java.util.HashMap$HashIterator.nextEntry(HashMap.java:787)
   at java.util.HashMap$ValueIterator.next(HashMap.java:817)
   at net.sf.hibernate.impl.Printer.toString(Printer.java:82)
   at net.sf.hibernate.impl.SessionImpl.flushEverything(SessionImpl.java:2302)
   at net.sf.hibernate.impl.SessionImpl.flush(SessionImpl.java:2260)
   at net.sf.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:61)
   at edu.academyart.model.graduate.TestPost.testPostWithCourseRole(TestPost.java:66)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at com.intellij.rt.execution.junit2.JUnitStarter.main(JUnitStarter.java:31)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at com.intellij.rt.execution.application.AppMain.main(AppMain.java:78)


Code:

Code:
        Session hibernate = getHibernateSession();
        Topic t = TestTopic.createTopic(hibernate);
        Course c = t.getCourse();
        CourseRole role = TestCourseRole.createCourseRole(hibernate, c);
        Post p = createPost(hibernate, t, role);
        assertNotNull("creation of post with CourseRole", p);

        hibernate.close();
        hibernate = getHibernateSession();
        p = (Post) hibernate.load(Post.class, p.getId());
        assertNotNull("restoring p", p);
        t = (Topic) hibernate.load(Topic.class, t.getId());
        assertEquals("topic contains the new post", t.getPosts().iterator().next(), p);
        c = t.getCourse();

        Transaction tx = hibernate.beginTransaction();
        logger.debug("HELLO DELETE");
        TestCourse.deleteCourse(hibernate, c);
        tx.commit();
        hibernate.close();


Debug level Hibernate log excerpt:

Code:
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2130 - object not resolved in any cache [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG EntityPersister:410 - Materializing entity: [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select course0_.id as id0_, course0_.semesterid as semesterid0_, course0_.name as name0_, course0_.description as descript4_0_, course0_.startdate as startdate0_, course0_.enddate as enddate0_, course0_.topclass as topclass0_, course0_.topclassid as topclassid0_, course0_.assignmentUploadActive as assignme9_0_, course0_.active as active0_, course0_.ftpActive as ftpActive0_, course0_.attachmentsizelimit as attachm12_0_ from course course0_ where course0_.id=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:484 - result row: 2231
13 Apr 2005 14:12:40 DEBUG Loader:615 - Initializing object from ResultSet: 2231
13 Apr 2005 14:12:40 DEBUG Loader:684 - Hydrating entity: edu.academyart.model.Course#2231
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (1 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG Loader:318 - total objects hydrated: 1
13 Apr 2005 14:12:40 DEBUG SessionImpl:2216 - resolving associations for [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.Semester#13]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Course.courseRoles#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Course.topics#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Course.grades#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Course.gradeTypes#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Course.assignments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Course.exams#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Course.examEnrollments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Course.announcements#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2247 - done materializing entity [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:1169 - deleting a persistent instance
13 Apr 2005 14:12:40 DEBUG SessionImpl:1189 - deleting [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.Course
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.Course.courseRoles
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.Course.courseRoles#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select courserole0_.roledObjectId as roledObj5___, courserole0_.id as id__, courserole0_.id as id1_, courserole0_.student as student1_, courserole0_.teacher as teacher1_, courserole0_.admin as admin1_, courserole0_.canPost as canPost1_, courserole0_.canView as canView1_, courserole0_.enrolled as enrolled1_, courserole0_.userId as userId1_, courserole0_.roledObjectType as roledObj4_1_, courserole0_.roledObjectId as roledObj5_1_, courserole0_.fromTopclass as fromTopc6_1_, courserole0_.accessPrincipalId as accessPr7_1_, accessprin1_.id as id0_, accessprin1_.name as name0_ from userRole courserole0_ left outer join accessPrincipal accessprin1_ on courserole0_.accessPrincipalId=accessprin1_.id where courserole0_.roledObjectId=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.Course.courseRoles#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:484 - result row: 3, 15208
13 Apr 2005 14:12:40 DEBUG Loader:615 - Initializing object from ResultSet: 3
13 Apr 2005 14:12:40 DEBUG Loader:684 - Hydrating entity: edu.academyart.model.AccessPrincipal#3
13 Apr 2005 14:12:40 DEBUG Loader:615 - Initializing object from ResultSet: 15208
13 Apr 2005 14:12:40 DEBUG Loader:684 - Hydrating entity: edu.academyart.model.CourseRole#15208
13 Apr 2005 14:12:40 DEBUG Loader:371 - found row of collection: [edu.academyart.model.Course.courseRoles#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3073 - reading row
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.CourseRole#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.CourseRole#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2110 - resolved object in session cache [edu.academyart.model.CourseRole#15208]
13 Apr 2005 14:12:40 DEBUG Loader:484 - result row: 3, 15209
13 Apr 2005 14:12:40 DEBUG Loader:615 - Initializing object from ResultSet: 15209
13 Apr 2005 14:12:40 DEBUG Loader:684 - Hydrating entity: edu.academyart.model.CourseRole#15209
13 Apr 2005 14:12:40  WARN SessionImpl:1859 - Narrowing proxy to class edu.academyart.model.CourseRole - this operation breaks ==
13 Apr 2005 14:12:40 DEBUG Loader:371 - found row of collection: [edu.academyart.model.Course.courseRoles#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3073 - reading row
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.CourseRole#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.CourseRole#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2110 - resolved object in session cache [edu.academyart.model.CourseRole#15209]
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (2 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG Loader:318 - total objects hydrated: 3
13 Apr 2005 14:12:40 DEBUG SessionImpl:2216 - resolving associations for [edu.academyart.model.AccessPrincipal#3]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2247 - done materializing entity [edu.academyart.model.AccessPrincipal#3]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2216 - resolving associations for [edu.academyart.model.CourseRole#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.CourseRole.announcementsViewed#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.CourseRole.examEnrollments#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.User#1]
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2110 - resolved object in session cache [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.UserRole.postsViewed#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.AccessPrincipal#3]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.AccessPrincipal#3]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2110 - resolved object in session cache [edu.academyart.model.AccessPrincipal#3]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2247 - done materializing entity [edu.academyart.model.CourseRole#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2216 - resolving associations for [edu.academyart.model.CourseRole#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.CourseRole.announcementsViewed#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.CourseRole.examEnrollments#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.User#2]
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2110 - resolved object in session cache [edu.academyart.model.Course#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.UserRole.postsViewed#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.AccessPrincipal#3]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.AccessPrincipal#3]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2110 - resolved object in session cache [edu.academyart.model.AccessPrincipal#3]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2247 - done materializing entity [edu.academyart.model.CourseRole#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.CourseRole#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2110 - resolved object in session cache [edu.academyart.model.CourseRole#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.Course.courseRoles#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:60 - cascading to delete()
13 Apr 2005 14:12:40 DEBUG SessionImpl:1169 - deleting a persistent instance
13 Apr 2005 14:12:40 DEBUG SessionImpl:1189 - deleting [edu.academyart.model.CourseRole#15208]
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.CourseRole
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.CourseRole.announcementsViewed
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.CourseRole.announcementsViewed#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select announceme0_.courseRoleId as courseRo2___, announceme0_.announcementId as announce1___ from courseRole_to_announcement_views announceme0_ where announceme0_.courseRoleId=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.CourseRole.announcementsViewed#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (0 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.CourseRole.announcementsViewed#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.CourseRole.examEnrollments
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.CourseRole.examEnrollments#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select examenroll0_.courseRoleId as courseRo2___, examenroll0_.id as id__, examenroll0_.id as id0_, examenroll0_.courseRoleId as courseRo2_0_, examenroll0_.exam_id as exam_id0_, examenroll0_.exam_time_id as exam_tim4_0_, examenroll0_.exam_proctor_id as exam_pro5_0_, examenroll0_.off_campus as off_campus0_, examenroll0_.course_id as course_id0_ from exam_enrollment examenroll0_ where examenroll0_.courseRoleId=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.CourseRole.examEnrollments#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (0 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG Loader:318 - total objects hydrated: 0
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.CourseRole.examEnrollments#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.CourseRole
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.CourseRole
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.CourseRole
13 Apr 2005 14:12:40 DEBUG Cascades:60 - cascading to delete()
13 Apr 2005 14:12:40 DEBUG SessionImpl:1169 - deleting a persistent instance
13 Apr 2005 14:12:40 DEBUG SessionImpl:1189 - deleting [edu.academyart.model.CourseRole#15209]
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.CourseRole
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.CourseRole.announcementsViewed
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.CourseRole.announcementsViewed#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select announceme0_.courseRoleId as courseRo2___, announceme0_.announcementId as announce1___ from courseRole_to_announcement_views announceme0_ where announceme0_.courseRoleId=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.CourseRole.announcementsViewed#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (0 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.CourseRole.announcementsViewed#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.CourseRole.examEnrollments
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.CourseRole.examEnrollments#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select examenroll0_.courseRoleId as courseRo2___, examenroll0_.id as id__, examenroll0_.id as id0_, examenroll0_.courseRoleId as courseRo2_0_, examenroll0_.exam_id as exam_id0_, examenroll0_.exam_time_id as exam_tim4_0_, examenroll0_.exam_proctor_id as exam_pro5_0_, examenroll0_.off_campus as off_campus0_, examenroll0_.course_id as course_id0_ from exam_enrollment examenroll0_ where examenroll0_.courseRoleId=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.CourseRole.examEnrollments#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (0 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG Loader:318 - total objects hydrated: 0
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.CourseRole.examEnrollments#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.CourseRole
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.CourseRole
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.CourseRole
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.Course.topics
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.Course.topics#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select topics0_.courseid as courseid__, topics0_.id as id__, topics0_.id as id0_, topics0_.courseid as courseid0_, topics0_.name as name0_, topics0_.description as descript4_0_, topics0_.courseRoleId as courseRo5_0_, topics0_.createdon as createdon0_, topics0_.endson as endson0_, topics0_.archived as archived0_, topics0_.publicTopic as publicTo9_0_, topics0_.orderNumber as orderNu10_0_ from topic topics0_ where topics0_.courseid=? order by topics0_.orderNumber
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.Course.topics#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:484 - result row: 5096
13 Apr 2005 14:12:40 DEBUG Loader:371 - found row of collection: [edu.academyart.model.Course.topics#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3073 - reading row
13 Apr 2005 14:12:40 DEBUG SessionImpl:1996 - loading [edu.academyart.model.Topic#5096]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.Topic#5096]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2110 - resolved object in session cache [edu.academyart.model.Topic#5096]
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (1 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG Loader:318 - total objects hydrated: 0
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.Course.topics#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:60 - cascading to delete()
13 Apr 2005 14:12:40 DEBUG SessionImpl:1169 - deleting a persistent instance
13 Apr 2005 14:12:40 DEBUG SessionImpl:1189 - deleting [edu.academyart.model.Topic#5096]
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.Topic
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.Topic.posts
13 Apr 2005 14:12:40 DEBUG Cascades:60 - cascading to delete()
13 Apr 2005 14:12:40 DEBUG SessionImpl:1169 - deleting a persistent instance
13 Apr 2005 14:12:40 DEBUG SessionImpl:1189 - deleting [edu.academyart.model.Post#10595]
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.Post
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.Post.attachments
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.Post.attachments#10595]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select attachment0_.postId as postId__, attachment0_.attachmentId as attachme2___ from post_to_attachment attachment0_ where attachment0_.postId=? order by attachment0_.attachmentId
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.Post.attachments#10595]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (0 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.Post.attachments#10595]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.Post
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.UserRole#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2110 - resolved object in session cache [edu.academyart.model.UserRole#15209]
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.Post
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.Post
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.Topic.attachments
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.Topic.attachments#5096]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select attachment0_.topicId as topicId__, attachment0_.attachmentId as attachme2___ from topic_to_attachment attachment0_ where attachment0_.topicId=? order by attachment0_.attachmentId
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.Topic.attachments#5096]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (0 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.Topic.attachments#5096]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.Topic
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.Topic
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.Topic
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.Course.grades
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.Course.grades#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select grades0_.courseid as courseid__, grades0_.id as id__, grades0_.id as id0_, grades0_.gradeTypeId as gradeTyp2_0_, grades0_.percentage as percentage0_, grades0_.comments as comments0_, grades0_.referral as referral0_, grades0_.entryDate as entryDate0_, grades0_.courseRoleId as courseRo7_0_, grades0_.courseId as courseId0_, grades0_.parentType as parentType0_, grades0_.parentId as parentId0_ from grade grades0_ where grades0_.courseid=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.Course.grades#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (0 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG Loader:318 - total objects hydrated: 0
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.Course.grades#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.Course.assignments
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.Course.assignments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select assignment0_.courseid as courseid__, assignment0_.id as id__, assignment0_.id as id0_, assignment0_.courseid as courseid0_, assignment0_.courseRoleId as courseRo3_0_, assignment0_.name as name0_, assignment0_.description as descript5_0_, assignment0_.location as location0_, assignment0_.ispublic as ispublic0_, assignment0_.uploaddate as uploaddate0_ from assignment assignment0_ where assignment0_.courseid=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.Course.assignments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (0 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG Loader:318 - total objects hydrated: 0
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.Course.assignments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:524 - cascading to collection: edu.academyart.model.Course.examEnrollments
13 Apr 2005 14:12:40 DEBUG SessionImpl:3307 - initializing collection [edu.academyart.model.Course.examEnrollments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3308 - checking second-level cache
13 Apr 2005 14:12:40 DEBUG SessionImpl:3314 - collection not cached
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select examenroll0_.course_id as course_id__, examenroll0_.id as id__ from exam_enrollment examenroll0_ where examenroll0_.course_id=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:406 - result set contains (possibly empty) collection: [edu.academyart.model.Course.examEnrollments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3050 - uninitialized collection: initializing
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (0 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG SessionImpl:3109 - 1 collections were found in result set
13 Apr 2005 14:12:40 DEBUG SessionImpl:3140 - collection fully initialized: [edu.academyart.model.Course.examEnrollments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3143 - 1 collections initialized
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:3316 - collection initialized
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.Course
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.Course
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.Course
13 Apr 2005 14:12:40 DEBUG JDBCTransaction:59 - commit
13 Apr 2005 14:12:40 DEBUG SessionImpl:2267 - flushing session
13 Apr 2005 14:12:40 DEBUG Cascades:497 - processing cascades for: edu.academyart.model.AccessPrincipal
13 Apr 2005 14:12:40 DEBUG Cascades:506 - done processing cascades for: edu.academyart.model.AccessPrincipal
13 Apr 2005 14:12:40 DEBUG SessionImpl:2467 - Flushing entities and processing referenced collections
13 Apr 2005 14:12:40 DEBUG AbstractEntityPersister:282 - edu.academyart.model.Topic.creator is dirty
13 Apr 2005 14:12:40 DEBUG SessionImpl:2558 - Updating deleted entity: [edu.academyart.model.Topic#5096]
13 Apr 2005 14:12:40 DEBUG AbstractEntityPersister:282 - edu.academyart.model.Post.creator is dirty
13 Apr 2005 14:12:40 DEBUG SessionImpl:2558 - Updating deleted entity: [edu.academyart.model.Post#10595]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2808 - Processing unreferenced collections
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Topic.posts#5096]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Topic.attachments#5096]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Post.attachments#10595]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Post.userRolesWhoHaveViewed#10595]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Course.courseRoles#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Course.topics#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Course.grades#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Course.gradeTypes#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Course.assignments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Course.exams#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Course.examEnrollments#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.Course.announcements#2231]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.CourseRole.announcementsViewed#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.CourseRole.examEnrollments#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.UserRole.postsViewed#15208]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.CourseRole.announcementsViewed#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.CourseRole.examEnrollments#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2933 - Collection dereferenced: [edu.academyart.model.UserRole.postsViewed#15209]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2822 - Scheduling collection removes/(re)creates/updates
13 Apr 2005 14:12:40 DEBUG SessionImpl:2291 - Flushed: 0 insertions, 2 updates, 5 deletions to 6 objects
13 Apr 2005 14:12:40 DEBUG SessionImpl:2296 - Flushed: 0 (re)creations, 0 updates, 18 removals to 18 collections
13 Apr 2005 14:12:40 DEBUG Printer:75 - listing entities:
13 Apr 2005 14:12:40 DEBUG Printer:82 - edu.academyart.model.Topic{creator=CourseRole#15208, attachments=[], publicTopic=true, archived=false, description=blahblah, posts=[Post#10595], order=1, endDate=2005-04-13 14:12:39, createDate=2005-04-13 14:12:38, course=Course#2231, name=blah, id=5096}
13 Apr 2005 14:12:40 DEBUG Printer:82 - edu.academyart.model.Post{attachmentGroup=null, createdDate=2005-04-13 14:12:39, parentPost=null, creator=UserRole#15209, attachments=[], editDate=2005-04-13 14:12:39, userRolesWhoHaveViewed=uninitialized, message=blahblah, provider=Topic#5096, subject=blah, id=10595}
13 Apr 2005 14:12:40 DEBUG Printer:82 - edu.academyart.model.AccessPrincipal{name=student, id=3}
13 Apr 2005 14:12:40 DEBUG SessionImpl:2094 - attempting to resolve [edu.academyart.model.Semester#13]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2130 - object not resolved in any cache [edu.academyart.model.Semester#13]
13 Apr 2005 14:12:40 DEBUG EntityPersister:410 - Materializing entity: [edu.academyart.model.Semester#13]
13 Apr 2005 14:12:40 DEBUG BatcherImpl:204 - about to open: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG SQL:230 - select semester0_.id as id0_, semester0_.name as name0_, semester0_.archived as archived0_, semester0_.startdate as startdate0_, semester0_.enddate as enddate0_, semester0_.attachmentsizelimit as attachme6_0_ from semester semester0_ where semester0_.id=?
13 Apr 2005 14:12:40 DEBUG BatcherImpl:253 - preparing statement
13 Apr 2005 14:12:40 DEBUG Loader:281 - processing result set
13 Apr 2005 14:12:40 DEBUG Loader:484 - result row: 13
13 Apr 2005 14:12:40 DEBUG Loader:615 - Initializing object from ResultSet: 13
13 Apr 2005 14:12:40 DEBUG Loader:684 - Hydrating entity: edu.academyart.model.Semester#13
13 Apr 2005 14:12:40 DEBUG Loader:298 - done processing result set (1 rows)
13 Apr 2005 14:12:40 DEBUG BatcherImpl:211 - done closing: 0 open PreparedStatements, 0 open ResultSets
13 Apr 2005 14:12:40 DEBUG BatcherImpl:275 - closing statement
13 Apr 2005 14:12:40 DEBUG Loader:318 - total objects hydrated: 1
13 Apr 2005 14:12:40 DEBUG SessionImpl:2216 - resolving associations for [edu.academyart.model.Semester#13]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Semester.courses#13]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3994 - creating collection wrapper:[edu.academyart.model.Semester.gradeDeadlines#13]
13 Apr 2005 14:12:40 DEBUG SessionImpl:2247 - done materializing entity [edu.academyart.model.Semester#13]
13 Apr 2005 14:12:40 DEBUG SessionImpl:3161 - initializing non-lazy collections
13 Apr 2005 14:12:40 DEBUG Printer:82 - edu.academyart.model.Course{active=true, startDate=2005-04-13 14:12:38, ftpActive=false, exams=uninitialized, topclassId=null, courseRoles=[CourseRole#15208, CourseRole#15209], attachmentSizeLimit=null, grades=[], examEnrollments=[], gradeTypes=uninitialized, id=2231, assignments=[], assignmentUploadActive=false, description=null, endDate=2005-04-13 14:12:38, name=BLAH, inTopclass=false, announcements=uninitialized, topics=[Topic#5096], semester=Semester#13}
Top
 Profile  
 
mpopetz
 Post subject:
PostPosted: Tue Apr 19, 2005 7:44 am 
Beginner
Beginner

Joined: Tue Oct 28, 2003 12:09 pm
Posts: 46
Bring this back up, it is in fact from a delete failing due to a cascading causing the deleted object to be saved.

"net.sf.hibernate.ObjectDeletedException: deleted object would be re-saved by cascade (remove deleted object from associations): 10287, of class: edu.academyart.model.CourseRole"

Unfortunately, I normally turn on hibernate debugging to help me track down my problem but can't in this case because then I get the ConcurrentModificationException when it tries to print the log.

Glurf.

-mp
Top
 Profile  
 
Display posts from previous:  Sort by  
Forum locked This topic is locked, you cannot edit posts or make further replies.  Page 1 of 1
  [ 2 posts ] 

Board index » Hibernate & Java Persistence » Hibernate Users

All times are UTC - 5 hours [ DST ]

You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum

Search for:
© Copyright 2014, Red Hat Inc. All rights reserved. JBoss and Hibernate are registered trademarks and servicemarks of Red Hat, Inc.