[hibernate-issues] [Hibernate-JIRA] Resolved: (HHH-6680) 2L caching appears to ignore collections

[ http://opensource.atlassian.com/projects/hibernate/browse/HHH-6680?page=c... ] Strong Liu resolved HHH-6680. ----------------------------- Resolution: Rejected 13:49:14,916 INFO Version:37 - HCANN000001: Hibernate Commons Annotations {4.0.0.CR2} 13:49:14,939 INFO Version:41 - HHH000412: Hibernate Core {[WORKING]} 13:49:14,951 INFO Environment:224 - HHH000205: Loaded properties from resource hibernate.properties: {hibernate.connection.username=sa, hibernate.cache.region_prefix=hibernate.test, hibernate.connection.pool_size=5, hibernate.dialect=org.hibernate.dialect.H2Dialect, hibernate.connection.url=jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1;MVCC=TRUE, hibernate.bytecode.use_reflection_optimizer=false, hibernate.connection.driver_class=org.h2.Driver} 13:49:14,953 INFO Environment:342 - HHH000021: Bytecode provider name : javassist 13:49:14,980 INFO Configuration:713 - HHH000221: Reading mappings from resource: org/hibernate/issue/Pet.hbm.xml 13:49:15,637 INFO DriverManagerConnectionProviderImpl:88 - HHH000402: Using Hibernate built-in connection pool (not for production use!) 13:49:15,641 INFO DriverManagerConnectionProviderImpl:108 - HHH000115: Hibernate connection pool size: 5 13:49:15,642 INFO DriverManagerConnectionProviderImpl:111 - HHH000006: Autocommit mode: true 13:49:15,642 INFO DriverManagerConnectionProviderImpl:125 - HHH000401: using driver [org.h2.Driver] at URL [jdbc:h2:mem:db1;DB_CLOSE_DELAY=-1;MVCC=TRUE] 13:49:15,643 INFO DriverManagerConnectionProviderImpl:128 - HHH000046: Connection properties: {user=sa, autocommit=true} 13:49:15,952 INFO Dialect:118 - HHH000400: Using dialect: org.hibernate.dialect.H2Dialect 13:49:15,965 INFO LobCreatorBuilder:94 - HHH000423: Disabling contextual LOB creation as JDBC driver reported JDBC version [3] less than 4 13:49:15,978 INFO TransactionFactoryInitiator:68 - HHH000399: Using default transaction strategy (direct JDBC transactions) 13:49:15,984 INFO ASTQueryTranslatorFactory:48 - HHH000397: Using ASTQueryTranslatorFactory 13:49:16,256 INFO Version:24 - Hibernate Validator 4.2.0.Final 13:49:16,393 WARN AbstractEhcacheRegionFactory:172 - HHH020003: Could not find a specific ehcache configuration for cache named [hibernate.test.org.hibernate.issue.Owner]; using defaults. 13:49:16,525 WARN AbstractEhcacheRegionFactory:172 - HHH020003: Could not find a specific ehcache configuration for cache named [hibernate.test.org.hibernate.issue.Pet]; using defaults. 13:49:16,536 WARN AbstractEhcacheRegionFactory:172 - HHH020003: Could not find a specific ehcache configuration for cache named [hibernate.test.org.hibernate.issue.Owner.pets]; using defaults. 13:49:16,592 INFO SchemaExport:330 - HHH000227: Running hbm2ddl schema export Hibernate: alter table dogs drop constraint FK2F22B76BD39EFC 13:49:16,603 ERROR SchemaExport:412 - HHH000389: Unsuccessful: alter table dogs drop constraint FK2F22B76BD39EFC 13:49:16,603 ERROR SchemaExport:3589 - Table "DOGS" not found; SQL statement: alter table dogs drop constraint FK2F22B76BD39EFC [42102-145] Hibernate: alter table owners_pets drop constraint FK26BE5BF3C5D57733 13:49:16,604 ERROR SchemaExport:412 - HHH000389: Unsuccessful: alter table owners_pets drop constraint FK26BE5BF3C5D57733 13:49:16,605 ERROR SchemaExport:3589 - Table "OWNERS_PETS" not found; SQL statement: alter table owners_pets drop constraint FK26BE5BF3C5D57733 [42102-145] Hibernate: alter table owners_pets drop constraint FK26BE5BF330B7F5FF 13:49:16,606 ERROR SchemaExport:412 - HHH000389: Unsuccessful: alter table owners_pets drop constraint FK26BE5BF330B7F5FF 13:49:16,606 ERROR SchemaExport:3589 - Table "OWNERS_PETS" not found; SQL statement: alter table owners_pets drop constraint FK26BE5BF330B7F5FF [42102-145] Hibernate: drop table dogs if exists Hibernate: drop table owners if exists Hibernate: drop table owners_pets if exists Hibernate: drop table pets if exists Hibernate: create table dogs ( id integer not null, feet integer, primary key (id) ) Hibernate: create table owners ( owner_id integer generated by default as identity, owner_name varchar(255) not null unique, primary key (owner_id) ) Hibernate: create table owners_pets ( id integer not null, pet_id integer not null, name varchar(255) not null, primary key (id, name) ) Hibernate: create table pets ( pet_id integer generated by default as identity, pet_type varchar(255) not null, pet_age integer, primary key (pet_id) ) Hibernate: alter table dogs add constraint FK2F22B76BD39EFC foreign key (id) references pets Hibernate: alter table owners_pets add constraint FK26BE5BF3C5D57733 foreign key (id) references owners Hibernate: alter table owners_pets add constraint FK26BE5BF330B7F5FF foreign key (pet_id) references pets 13:49:16,639 INFO SchemaExport:392 - HHH000230: Schema export complete 13:49:16,643 INFO UpdateTimestampsCache:63 - HHH000250: Starting update timestamps cache at region: hibernate.test.org.hibernate.cache.spi.UpdateTimestampsCache 13:49:16,646 WARN AbstractEhcacheRegionFactory:172 - HHH020003: Could not find a specific ehcache configuration for cache named [hibernate.test.org.hibernate.cache.spi.UpdateTimestampsCache]; using defaults. 13:49:16,649 INFO StandardQueryCache:80 - HHH000248: Starting query cache at region: hibernate.test.org.hibernate.cache.internal.StandardQueryCache 13:49:16,650 WARN AbstractEhcacheRegionFactory:172 - HHH020003: Could not find a specific ehcache configuration for cache named [hibernate.test.org.hibernate.cache.internal.StandardQueryCache]; using defaults. Hibernate: insert into owners (owner_id, owner_name) values (null, ?) Hibernate: insert into pets (pet_id, pet_age, pet_type) values (null, ?, 'dog') Hibernate: insert into dogs (feet, id) values (?, ?) Hibernate: insert into owners_pets (id, name, pet_id) values (?, ?, ?) Hibernate: select owner0_.owner_id as owner1_0_, owner0_.owner_name as owner2_0_ from owners owner0_ Hibernate: select pets0_.id as id0_1_, pets0_.pet_id as pet2_1_, pets0_.name as name1_, pet1_.pet_id as pet1_2_0_, pet1_.pet_age as pet3_2_0_, pet1_.pet_type as pet2_2_0_ from owners_pets pets0_ inner join pets pet1_ on pets0_.pet_id=pet1_.pet_id where pets0_.id=? Hibernate: select pet_1_.feet as feet3_ from dogs pet_1_ where pet_1_.id=? 13:49:17,060 INFO ConcurrentStatisticsImpl:332 - HHH000117: HQL: select o from Owner o, time: 26ms, rows: 1 13:49:18,012 INFO ConcurrentStatisticsImpl:580 - HHH000161: Logging statistics.... 13:49:18,013 INFO ConcurrentStatisticsImpl:581 - HHH000251: Start time: 1318139357063 13:49:18,014 INFO ConcurrentStatisticsImpl:582 - HHH000242: Sessions opened: 1 13:49:18,014 INFO ConcurrentStatisticsImpl:583 - HHH000241: Sessions closed: 1 13:49:18,015 INFO ConcurrentStatisticsImpl:584 - HHH000266: Transactions: 1 13:49:18,015 INFO ConcurrentStatisticsImpl:585 - HHH000258: Successful transactions: 1 13:49:18,015 INFO ConcurrentStatisticsImpl:586 - HHH000187: Optimistic lock failures: 0 13:49:18,016 INFO ConcurrentStatisticsImpl:587 - HHH000105: Flushes: 1 13:49:18,016 INFO ConcurrentStatisticsImpl:588 - HHH000048: Connections obtained: 1 13:49:18,016 INFO ConcurrentStatisticsImpl:589 - HHH000253: Statements prepared: 0 13:49:18,017 INFO ConcurrentStatisticsImpl:590 - HHH000252: Statements closed: 0 13:49:18,017 INFO ConcurrentStatisticsImpl:591 - HHH000239: Second level cache puts: 0 13:49:18,018 INFO ConcurrentStatisticsImpl:592 - HHH000237: Second level cache hits: 3 13:49:18,020 INFO ConcurrentStatisticsImpl:593 - HHH000238: Second level cache misses: 0 13:49:18,020 INFO ConcurrentStatisticsImpl:594 - HHH000079: Entities loaded: 0 13:49:18,020 INFO ConcurrentStatisticsImpl:595 - HHH000080: Entities updated: 0 13:49:18,021 INFO ConcurrentStatisticsImpl:596 - HHH000078: Entities inserted: 0 13:49:18,021 INFO ConcurrentStatisticsImpl:597 - HHH000076: Entities deleted: 0 13:49:18,022 INFO ConcurrentStatisticsImpl:598 - HHH000077: Entities fetched (minimize this): 0 13:49:18,022 INFO ConcurrentStatisticsImpl:599 - HHH000033: Collections loaded: 0 13:49:18,022 INFO ConcurrentStatisticsImpl:600 - HHH000036: Collections updated: 0 13:49:18,023 INFO ConcurrentStatisticsImpl:601 - HHH000035: Collections removed: 0 13:49:18,023 INFO ConcurrentStatisticsImpl:602 - HHH000034: Collections recreated: 0 13:49:18,023 INFO ConcurrentStatisticsImpl:603 - HHH000032: Collections fetched (minimize this): 0 13:49:18,024 INFO ConcurrentStatisticsImpl:604 - HHH000210: Queries executed to database: 0 13:49:18,024 INFO ConcurrentStatisticsImpl:605 - HHH000215: Query cache puts: 0 13:49:18,024 INFO ConcurrentStatisticsImpl:606 - HHH000433: update timestamps cache puts: 0 13:49:18,026 INFO ConcurrentStatisticsImpl:607 - HHH000434: update timestamps cache hits: 2500 13:49:18,027 INFO ConcurrentStatisticsImpl:608 - HHH000435: update timestamps cache misses: 0 13:49:18,028 INFO ConcurrentStatisticsImpl:609 - HHH000213: Query cache hits: 2500 13:49:18,028 INFO ConcurrentStatisticsImpl:610 - HHH000214: Query cache misses: 0 13:49:18,029 INFO ConcurrentStatisticsImpl:611 - HHH000173: Max query time: 0ms 13:49:18,057 INFO UpdateChecker:98 - New update(s) found: 2.4.5 [http://www.terracotta.org/confluence/display/release/Release+Notes+Ehcach...]. Please check http://ehcache.org for the latest version. Process finished with exit code 0 -- This message is automatically generated by JIRA. For more information on JIRA, see: http://www.atlassian.com/software/jira