[jboss-user] [JBoss Cache: Core Edition] - Does the log show my entities are loaded from cache?

[zlf]

anonymous wrote : 2008-09-10 15:57:31,546 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 5001353017532416
  | 2008-09-10 15:57:31,546 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] Looking for a JTA transaction to join
  | 2008-09-10 15:57:31,546 DEBUG [org.hibernate.jdbc.JDBCContext] successfully registered Synchronization
  | 2008-09-10 15:57:31,546 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] Looking for a JTA transaction to join
  | 2008-09-10 15:57:31,546 DEBUG [org.hibernate.ejb.AbstractEntityManagerImpl] Transaction already joined
  | 2008-09-10 15:57:31,546 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
  | 2008-09-10 15:57:31,546 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection
  | 2008-09-10 15:57:33,281 DEBUG [org.hibernate.SQL] /* select s from ContestStatus s */ select conteststa0_.contest_detailed_status_id as contest1_10_, conteststa0_.contest_detailed_status_desc as contest2_10_, conteststa0_.name as name10_, conteststa0_.contest_status_id as contest4_10_ from contest_detailed_status_lu conteststa0_
  | 2008-09-10 15:57:33,281 INFO  [STDOUT] Hibernate: /* select s from ContestStatus s */ select conteststa0_.contest_detailed_status_id as contest1_10_, conteststa0_.contest_detailed_status_desc as contest2_10_, conteststa0_.name as name10_, conteststa0_.contest_status_id as contest4_10_ from contest_detailed_status_lu conteststa0_
  | 2008-09-10 15:57:34,250 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
  | 2008-09-10 15:57:34,250 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.mycompany.ContestStatus#6]
  | 2008-09-10 15:57:34,250 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.mycompany.ContestStatus#1]
  | 2008-09-10 15:57:34,250 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.mycompany.ContestStatus#2]
  | 2008-09-10 15:57:34,250 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.mycompany.ContestStatus#3]
  | 2008-09-10 15:57:34,250 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.mycompany.ContestStatus#4]
  | 2008-09-10 15:57:34,250 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.mycompany.ContestStatus#5]
  | 2008-09-10 15:57:34,250 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.mycompany.ContestStatus#7]
  | 2008-09-10 15:57:34,250 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.mycompany.ContestStatus#8]
  | 2008-09-10 15:57:34,562 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
  | 2008-09-10 15:57:34,921 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively releasing JDBC connection
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.mycompany.ContestStatus#6]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.mycompany.ContestStatus#6]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.mycompany.ContestStatus#1]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.mycompany.ContestStatus#1]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.mycompany.ContestStatus#2]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.mycompany.ContestStatus#2]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.mycompany.ContestStatus#3]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.mycompany.ContestStatus#3]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.mycompany.ContestStatus#4]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.mycompany.ContestStatus#4]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.mycompany.ContestStatus#5]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.mycompany.ContestStatus#5]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.mycompany.ContestStatus#7]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.mycompany.ContestStatus#7]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.mycompany.ContestStatus#8]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.mycompany.ContestStatus#8]
  | 2008-09-10 15:57:35,234 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections

My ehcache configuration is
anonymous wrote : 
  |     
  | 
  |     
  |     

I call "select * from ContestStatus" twice, the log is from the second time calling. From the log, I guess ContestStatus entities are not retrieved from cache, loaded from another sql queries. But cannot figure out what is the problem.

View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=4175497#4175497

Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=4175497