net.sf.hibernate.JDBCException

There are no available Samebug tips for this exception. Do you have an idea how to solve this issue? A short tip would help users who saw this issue last week.

  • I'm trying to run the sample from "Chapter 10. A Worked Example", i write additional code in the BlogMain class(rename as Main): public static void main(String[] args) { new Main(); } public Main() { try { configure(); exportTables(); Blog blog; for(int i=0;i<2;i++){ blog = createBlog("Blog_" + i); for(int j=0;j<2;j++){ createBlogItem(blog,"item_" + j,"blablabla hahaha tralala"); } } List list = listAllBlogNamesAndItemCounts(100); java.util.Iterator it = list.iterator(); while(it.hasNext()){ Object o = it.next(); System.out.println(o); } } catch (Exception e) { e.printStackTrace(); } } listAllBlogNamesAndItemCounts method throw an SQLException: Hibernate: select blog0_.BLOG_ID as x0_0_, blog0_.NAME as x1_0_, count(items1_.BLOG_ITEM_ID) as x2_0_ from BLOGS blog0_ left outer join BLOG_ITEMS items1_ on blog0_.BLOG_ID=items1_.BLOG_ID group by blog0_.NAME , blog0_.BLOG_ID order by max(items1_.DATE_TIME) limit ?, ? 16:10:33,710 DEBUG JDBCExceptionReporter:36 - SQL Exception java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) ######################## # hibernate.properties # ######################## hibernate.query.imports eg hibernate.dialect net.sf.hibernate.dialect.MySQLDialect hibernate.connection.driver_class org.gjt.mm.mysql.Driver #hibernate.connection.driver_class com.mysql.jdbc.Driver hibernate.connection.url jdbc:mysql:///hibernate hibernate.connection.username root hibernate.connection.password hibernate.connection.pool_size 1 hibernate.statement_cache.size 25 hibernate.show_sql true ######## # logs # ######## 16:10:28,320 INFO Environment:403 - Hibernate 2.0.3 16:10:28,380 INFO Environment:437 - loaded properties from resource hibernate.properties: {hibernate.connection.driver_class=org.gjt.mm.mysql.Driver, hibernate.cglib.use_reflection_optimizer=true, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, hibernate.query.imports=eg, hibernate.connection.username=root, hibernate.connection.url=jdbc:mysql:///hibernate, hibernate.show_sql=true, hibernate.connection.password=, hibernate.statement_cache.size=25, hibernate.connection.pool_size=1} 16:10:28,430 INFO Environment:452 - using CGLIB reflection optimizer 16:10:28,430 INFO Environment:462 - JVM proxy support: true 16:10:28,490 INFO Configuration:283 - Mapping resource: eg/Blog.hbm.xml 16:10:29,700 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 16:10:29,700 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath 16:10:30,080 INFO Binder:178 - Mapping class: eg.Blog -> BLOGS 16:10:30,250 DEBUG Binder:394 - Mapped property: id -> BLOG_ID, type: long 16:10:30,300 DEBUG Binder:394 - Mapped property: name -> NAME, type: string 16:10:30,360 DEBUG Binder:394 - Mapped property: items, type: java.util.Collection 16:10:30,360 INFO Configuration:283 - Mapping resource: eg/BlogItem.hbm.xml 16:10:30,360 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 16:10:30,360 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath 16:10:30,410 INFO Binder:178 - Mapping class: eg.BlogItem -> BLOG_ITEMS 16:10:30,410 DEBUG Binder:394 - Mapped property: id -> BLOG_ITEM_ID, type: long 16:10:30,410 DEBUG Binder:394 - Mapped property: title -> TITLE, type: string 16:10:30,460 DEBUG Binder:394 - Mapped property: text -> TEXT, type: string 16:10:30,460 DEBUG Binder:394 - Mapped property: datetime -> DATE_TIME, type: calendar 16:10:30,460 DEBUG Binder:394 - Mapped property: blog -> BLOG_ID, type: eg.Blog 16:10:30,460 INFO Configuration:492 - processing one-to-many association mappings 16:10:30,460 DEBUG Binder:1134 - Second pass for collection: eg.Blog.items 16:10:30,460 INFO Binder:1025 - Mapping collection: eg.Blog.items -> BLOG_ITEMS 16:10:30,520 DEBUG Binder:1146 - Mapped collection key: BLOG_ID, one-to-many: eg.BlogItem 16:10:30,520 INFO Configuration:503 - processing foreign key constraints 16:10:30,520 DEBUG Configuration:513 - resolving reference to class: eg.Blog 16:10:30,850 INFO SessionFactoryImpl:132 - building session factory 16:10:30,850 DEBUG SessionFactoryImpl:134 - instantiating session factory with properties: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.connection.password=, sun.boot.library.path=C:\J2SDK1.4.2_01\JRE\bin, java.vm.version=1.4.2_01-b06, hibernate.connection.username=root, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=FR, sun.os.patch.level= A , java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\WINDOWS\Bureau\HibernateTest, java.runtime.version=1.4.2_01-b06, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\J2SDK1.4.2_01\JRE\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\WINDOWS\TEMP\, line.separator= , java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows 98, sun.java2d.fontpath=, java.library.path=C:\J2SDK1.4.2_01\BIN;.;C:\WINDOWS\SYSTEM;C:\WINDOWS;C:\JWSDP-1.2\JWSDP-SHARED\BIN;C:\WINDOWS;C:\WINDOWS\COMMAND;C:\J2SDK1.4.2_01\BIN, java.specification.name=Java Platform API Specification, java.class.version=48.0, hibernate.connection.pool_size=1, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, os.version=4.10, user.home=C:\WINDOWS, user.timezone=Europe/Paris, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.driver_class=org.gjt.mm.mysql.Driver, user.name=Laurent Bauchau, java.class.path=C:\WINDOWS\Bureau\HibernateTest\src;C:\hibernate-2.0.3\hibernate2.jar;C:\hibernate-2.0.3\lib\commons-lang.jar;C:\hibernate-2.0.3\lib\dom4j.jar;C:\hibernate-2.0.3\lib\commons-logging.jar;C:\hibernate-2.0.3\lib\commons-beanutils.jar;C:\hibernate-2.0.3\lib\commons-dbcp.jar;C:\hibernate-2.0.3\lib\commons-collections.jar;C:\hibernate-2.0.3\lib\commons-pool.jar;C:\hibernate-2.0.3\lib\xml-apis.jar;C:\hibernate-2.0.3\lib\c3p0.jar;C:\hibernate-2.0.3\lib\cglib-asm.jar;C:\hibernate-2.0.3\lib\connector.jar;C:\hibernate-2.0.3\lib\jaas.jar;C:\hibernate-2.0.3\lib\jcs.jar;C:\hibernate-2.0.3\lib\jdbc2_0-stdext.jar;C:\hibernate-2.0.3\lib\jta.jar;C:\hibernate-2.0.3\lib\junit.jar;C:\hibernate-2.0.3\lib\odmg.jar;C:\hibernate-2.0.3\lib\optional.jar;C:\hibernate-2.0.3\lib\proxool.jar;C:\hibernate-2.0.3\lib\xalan.jar;C:\hibernate-2.0.3\lib\xerces.jar;C:\hibernate-2.0.3\lib\ant.jar;E:\downloads\mm.mysql-2.0.14\mm.mysql-2.0.14-bin.jar;C:\jakarta-log4j-1.2.8\dist\lib\log4j-1.2.8.jar, hibernate.show_sql=true, java.vm.specification.version=1.0, sun.arch.data.model=32, java.home=C:\J2SDK1.4.2_01\JRE, hibernate.connection.url=jdbc:mysql:///hibernate, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, java.specification.vendor=Sun Microsystems Inc., user.language=fr, awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, hibernate.cglib.use_reflection_optimizer=true, java.version=1.4.2_01, java.ext.dirs=C:\J2SDK1.4.2_01\JRE\lib\ext, sun.boot.class.path=C:\J2SDK1.4.2_01\JRE\lib\rt.jar;C:\J2SDK1.4.2_01\JRE\lib\i18n.jar;C:\J2SDK1.4.2_01\JRE\lib\sunrsasign.jar;C:\J2SDK1.4.2_01\JRE\lib\jsse.jar;C:\J2SDK1.4.2_01\JRE\lib\jce.jar;C:\J2SDK1.4.2_01\JRE\lib\charsets.jar;C:\J2SDK1.4.2_01\JRE\classes, java.vendor=Sun Microsystems Inc., file.separator=\, hibernate.query.imports=eg, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, hibernate.statement_cache.size=25, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.isalist=pentium i486 i386} 16:10:30,900 INFO Dialect:83 - Using dialect: net.sf.hibernate.dialect.MySQLDialect 16:10:30,960 INFO DriverManagerConnectionProvider:41 - Hibernate connection pool size: 1 16:10:30,960 INFO DriverManagerConnectionProvider:70 - using driver: org.gjt.mm.mysql.Driver at URL: jdbc:mysql:///hibernate 16:10:30,960 INFO DriverManagerConnectionProvider:71 - connection properties: {user=root, password=} 16:10:30,960 INFO PreparedStatementCache:60 - prepared statement cache size: 25 16:10:31,010 INFO SessionFactoryImpl:162 - Use outer join fetching: true 16:10:31,010 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:31,010 DEBUG DriverManagerConnectionProvider:90 - opening new JDBC connection 16:10:31,230 DEBUG DriverManagerConnectionProvider:103 - created connection to: jdbc:mysql:///hibernate, Isolation Level: 2 16:10:31,230 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:31,230 INFO SessionFactoryImpl:185 - Use scrollable result sets: true 16:10:31,230 INFO SessionFactoryImpl:186 - JDBC 2 max batch size: 15 16:10:31,290 INFO SessionFactoryImpl:194 - echoing all SQL to stdout 16:10:32,110 DEBUG SessionFactoryObjectFactory:39 - initializing class SessionFactoryObjectFactory 16:10:32,170 DEBUG SessionFactoryObjectFactory:76 - registered: 4028ef83f869483a00f86948402e0000 (unnamed) 16:10:32,170 INFO SessionFactoryObjectFactory:82 - no JDNI name configured 16:10:32,170 INFO SessionFactoryImpl:269 - Query language substitutions: {} 16:10:32,170 DEBUG SessionFactoryImpl:281 - instantiated session factory 16:10:32,170 INFO Configuration:283 - Mapping resource: eg/Blog.hbm.xml 16:10:32,170 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 16:10:32,170 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath 16:10:32,220 INFO Binder:178 - Mapping class: eg.Blog -> BLOGS 16:10:32,220 DEBUG Binder:394 - Mapped property: id -> BLOG_ID, type: long 16:10:32,220 DEBUG Binder:394 - Mapped property: name -> NAME, type: string 16:10:32,220 DEBUG Binder:394 - Mapped property: items, type: java.util.Collection 16:10:32,280 INFO Configuration:283 - Mapping resource: eg/BlogItem.hbm.xml 16:10:32,280 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 16:10:32,280 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath 16:10:32,330 INFO Binder:178 - Mapping class: eg.BlogItem -> BLOG_ITEMS 16:10:32,330 DEBUG Binder:394 - Mapped property: id -> BLOG_ITEM_ID, type: long 16:10:32,330 DEBUG Binder:394 - Mapped property: title -> TITLE, type: string 16:10:32,330 DEBUG Binder:394 - Mapped property: text -> TEXT, type: string 16:10:32,330 DEBUG Binder:394 - Mapped property: datetime -> DATE_TIME, type: calendar 16:10:32,390 DEBUG Binder:394 - Mapped property: blog -> BLOG_ID, type: eg.Blog 16:10:32,390 INFO Dialect:83 - Using dialect: net.sf.hibernate.dialect.MySQLDialect 16:10:32,390 INFO Configuration:492 - processing one-to-many association mappings 16:10:32,390 DEBUG Binder:1134 - Second pass for collection: eg.Blog.items 16:10:32,390 INFO Binder:1025 - Mapping collection: eg.Blog.items -> BLOG_ITEMS 16:10:32,390 DEBUG Binder:1146 - Mapped collection key: BLOG_ID, one-to-many: eg.BlogItem 16:10:32,390 INFO Configuration:503 - processing foreign key constraints 16:10:32,390 DEBUG Configuration:513 - resolving reference to class: eg.Blog 16:10:32,390 INFO Configuration:492 - processing one-to-many association mappings 16:10:32,390 INFO Configuration:503 - processing foreign key constraints 16:10:32,390 INFO DriverManagerConnectionProvider:41 - Hibernate connection pool size: 1 16:10:32,390 INFO DriverManagerConnectionProvider:70 - using driver: org.gjt.mm.mysql.Driver at URL: jdbc:mysql:///hibernate 16:10:32,390 INFO DriverManagerConnectionProvider:71 - connection properties: {user=root, password=} 16:10:32,440 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:32,440 DEBUG DriverManagerConnectionProvider:90 - opening new JDBC connection 16:10:32,500 DEBUG DriverManagerConnectionProvider:103 - created connection to: jdbc:mysql:///hibernate, Isolation Level: 2 drop table BLOGS drop table BLOG_ITEMS create table BLOGS ( BLOG_ID BIGINT NOT NULL AUTO_INCREMENT, NAME VARCHAR(255) not null unique, primary key (BLOG_ID) ) create table BLOG_ITEMS ( BLOG_ITEM_ID BIGINT NOT NULL AUTO_INCREMENT, TITLE VARCHAR(255) not null, TEXT VARCHAR(255) not null, DATE_TIME DATETIME not null, BLOG_ID BIGINT not null, primary key (BLOG_ITEM_ID) ) alter table BLOG_ITEMS add index (BLOG_ID), add constraint FK5FDFB8C329868AD8 foreign key (BLOG_ID) references BLOGS (BLOG_ID) 16:10:32,550 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:32,550 INFO DriverManagerConnectionProvider:144 - cleaning up connection pool: jdbc:mysql:///hibernate 16:10:32,610 INFO DriverManagerConnectionProvider:144 - cleaning up connection pool: jdbc:mysql:///hibernate 16:10:32,660 DEBUG SessionImpl:413 - opened session 16:10:32,660 DEBUG JDBCTransaction:36 - begin 16:10:32,660 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:32,660 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:32,660 DEBUG SessionImpl:656 - saving [eg.Blog#<null>] 16:10:32,660 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,660 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,660 DEBUG SessionImpl:2520 - Wrapped collection in role: eg.Blog.items 16:10:32,720 DEBUG EntityPersister:495 - Inserting entity: eg.Blog (native id) 16:10:32,720 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,720 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOGS (NAME) values (?) Hibernate: insert into BLOGS (NAME) values (?) 16:10:32,770 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#null 16:10:32,770 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,830 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,830 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:32,830 DEBUG EntityPersister:528 - Natively generated identity: 1 16:10:32,830 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,830 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,830 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:32,830 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,830 DEBUG JDBCTransaction:54 - commit 16:10:32,830 DEBUG SessionImpl:2011 - flushing session 16:10:32,830 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,830 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:32,830 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,830 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:32,880 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#1], was: [<unreferenced>] 16:10:32,880 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:32,880 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:32,880 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects 16:10:32,880 DEBUG SessionImpl:2028 - Flushed: 1 (re)creations, 0 updates, 0 removals to 1 collections 16:10:32,880 DEBUG SessionImpl:2058 - executing flush 16:10:32,880 DEBUG SessionImpl:2428 - post flush 16:10:32,880 DEBUG SessionImpl:447 - transaction completion 16:10:32,880 DEBUG SessionImpl:435 - closing session 16:10:32,880 DEBUG SessionImpl:2930 - disconnecting session 16:10:32,880 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:32,880 DEBUG SessionImpl:447 - transaction completion 16:10:32,880 DEBUG SessionImpl:413 - opened session 16:10:32,880 DEBUG JDBCTransaction:36 - begin 16:10:32,880 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:32,880 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:32,880 DEBUG SessionImpl:1270 - updating [eg.Blog#1] 16:10:32,880 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,880 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:32,880 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:32,880 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:32,940 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null 16:10:32,940 DEBUG SessionImpl:656 - saving [eg.BlogItem#<null>] 16:10:32,940 DEBUG EntityPersister:495 - Inserting entity: eg.BlogItem (native id) 16:10:32,940 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,940 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) Hibernate: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) 16:10:32,940 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#null 16:10:32,940 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,940 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,940 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:32,940 DEBUG EntityPersister:528 - Natively generated identity: 1 16:10:32,940 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,940 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,940 DEBUG JDBCTransaction:54 - commit 16:10:32,940 DEBUG SessionImpl:2011 - flushing session 16:10:32,940 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,940 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:32,940 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:32,940 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:32,940 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,940 DEBUG SessionImpl:268 - Collection dirty: [eg.Blog.items#1] 16:10:32,940 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:32,990 DEBUG SessionImpl:2209 - Updating entity: [eg.Blog#1] 16:10:32,990 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#1], was: [eg.Blog.items#1] 16:10:32,990 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:32,990 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:32,990 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 1 updates, 0 deletions to 2 objects 16:10:32,990 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 1 updates, 0 removals to 1 collections 16:10:32,990 DEBUG SessionImpl:2058 - executing flush 16:10:32,990 DEBUG EntityPersister:617 - Updating entity: eg.Blog#1 16:10:32,990 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,990 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOGS set NAME=? where BLOG_ID=? Hibernate: update BLOGS set NAME=? where BLOG_ID=? 16:10:32,990 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#1 16:10:32,990 DEBUG BatcherImpl:24 - Adding to batch 16:10:32,990 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:32,990 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,990 DEBUG SessionImpl:2428 - post flush 16:10:32,990 DEBUG SessionImpl:447 - transaction completion 16:10:32,990 DEBUG SessionImpl:435 - closing session 16:10:32,990 DEBUG SessionImpl:2930 - disconnecting session 16:10:32,990 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:32,990 DEBUG SessionImpl:447 - transaction completion 16:10:33,050 DEBUG SessionImpl:413 - opened session 16:10:33,050 DEBUG JDBCTransaction:36 - begin 16:10:33,050 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,050 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,050 DEBUG SessionImpl:1270 - updating [eg.Blog#1] 16:10:33,050 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,050 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,050 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,050 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,050 DEBUG SessionImpl:1205 - saveOrUpdate() previously saved instance with id: 1 16:10:33,050 DEBUG SessionImpl:1270 - updating [eg.BlogItem#1] 16:10:33,050 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,050 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,050 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null 16:10:33,050 DEBUG SessionImpl:2981 - running Session.finalize() 16:10:33,050 DEBUG SessionImpl:656 - saving [eg.BlogItem#<null>] 16:10:33,050 DEBUG EntityPersister:495 - Inserting entity: eg.BlogItem (native id) 16:10:33,050 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,050 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) Hibernate: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) 16:10:33,050 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#null 16:10:33,050 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,050 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,050 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:33,050 DEBUG EntityPersister:528 - Natively generated identity: 2 16:10:33,050 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,050 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,100 DEBUG JDBCTransaction:54 - commit 16:10:33,100 DEBUG SessionImpl:2011 - flushing session 16:10:33,100 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,100 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,100 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,100 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,100 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,100 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,100 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,100 DEBUG SessionImpl:268 - Collection dirty: [eg.Blog.items#1] 16:10:33,100 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,100 DEBUG SessionImpl:2209 - Updating entity: [eg.Blog#1] 16:10:33,100 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#1], was: [eg.Blog.items#1] 16:10:33,100 DEBUG SessionImpl:2209 - Updating entity: [eg.BlogItem#1] 16:10:33,100 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,100 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,100 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 2 updates, 0 deletions to 3 objects 16:10:33,100 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 1 updates, 0 removals to 1 collections 16:10:33,100 DEBUG SessionImpl:2058 - executing flush 16:10:33,100 DEBUG EntityPersister:617 - Updating entity: eg.Blog#1 16:10:33,100 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,100 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOGS set NAME=? where BLOG_ID=? Hibernate: update BLOGS set NAME=? where BLOG_ID=? 16:10:33,100 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#1 16:10:33,100 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,100 DEBUG EntityPersister:617 - Updating entity: eg.BlogItem#1 16:10:33,100 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,100 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,100 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,100 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOG_ITEMS set TITLE=?, TEXT=?, DATE_TIME=?, BLOG_ID=? where BLOG_ITEM_ID=? Hibernate: update BLOG_ITEMS set TITLE=?, TEXT=?, DATE_TIME=?, BLOG_ID=? where BLOG_ITEM_ID=? 16:10:33,100 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#1 16:10:33,100 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,100 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,160 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,160 DEBUG SessionImpl:2428 - post flush 16:10:33,160 DEBUG SessionImpl:447 - transaction completion 16:10:33,160 DEBUG SessionImpl:435 - closing session 16:10:33,160 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,160 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,160 DEBUG SessionImpl:447 - transaction completion 16:10:33,160 DEBUG SessionImpl:413 - opened session 16:10:33,160 DEBUG JDBCTransaction:36 - begin 16:10:33,160 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,160 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,160 DEBUG SessionImpl:656 - saving [eg.Blog#<null>] 16:10:33,160 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,160 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,160 DEBUG SessionImpl:2520 - Wrapped collection in role: eg.Blog.items 16:10:33,160 DEBUG EntityPersister:495 - Inserting entity: eg.Blog (native id) 16:10:33,160 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,160 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOGS (NAME) values (?) Hibernate: insert into BLOGS (NAME) values (?) 16:10:33,160 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#null 16:10:33,160 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,160 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,160 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:33,160 DEBUG EntityPersister:528 - Natively generated identity: 2 16:10:33,160 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,210 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,210 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,210 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,210 DEBUG JDBCTransaction:54 - commit 16:10:33,210 DEBUG SessionImpl:2011 - flushing session 16:10:33,210 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,210 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,210 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,210 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,210 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#2], was: [<unreferenced>] 16:10:33,210 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,210 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,210 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects 16:10:33,210 DEBUG SessionImpl:2028 - Flushed: 1 (re)creations, 0 updates, 0 removals to 1 collections 16:10:33,210 DEBUG SessionImpl:2058 - executing flush 16:10:33,210 DEBUG SessionImpl:2428 - post flush 16:10:33,210 DEBUG SessionImpl:447 - transaction completion 16:10:33,210 DEBUG SessionImpl:435 - closing session 16:10:33,210 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,210 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,210 DEBUG SessionImpl:447 - transaction completion 16:10:33,210 DEBUG SessionImpl:413 - opened session 16:10:33,210 DEBUG JDBCTransaction:36 - begin 16:10:33,210 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,210 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,210 DEBUG SessionImpl:1270 - updating [eg.Blog#2] 16:10:33,210 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,210 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,270 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,270 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,270 DEBUG SessionImpl:2981 - running Session.finalize() 16:10:33,270 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null 16:10:33,270 DEBUG SessionImpl:656 - saving [eg.BlogItem#<null>] 16:10:33,270 DEBUG EntityPersister:495 - Inserting entity: eg.BlogItem (native id) 16:10:33,270 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,270 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) Hibernate: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) 16:10:33,270 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#null 16:10:33,270 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,270 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,270 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:33,270 DEBUG EntityPersister:528 - Natively generated identity: 3 16:10:33,270 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,270 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,270 DEBUG JDBCTransaction:54 - commit 16:10:33,270 DEBUG SessionImpl:2011 - flushing session 16:10:33,270 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,270 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,320 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,320 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,320 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,320 DEBUG SessionImpl:268 - Collection dirty: [eg.Blog.items#2] 16:10:33,320 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,320 DEBUG SessionImpl:2209 - Updating entity: [eg.Blog#2] 16:10:33,320 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#2], was: [eg.Blog.items#2] 16:10:33,320 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,320 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,320 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 1 updates, 0 deletions to 2 objects 16:10:33,320 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 1 updates, 0 removals to 1 collections 16:10:33,320 DEBUG SessionImpl:2058 - executing flush 16:10:33,320 DEBUG EntityPersister:617 - Updating entity: eg.Blog#2 16:10:33,320 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,320 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOGS set NAME=? where BLOG_ID=? Hibernate: update BLOGS set NAME=? where BLOG_ID=? 16:10:33,320 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#2 16:10:33,320 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,320 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,320 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,320 DEBUG SessionImpl:2428 - post flush 16:10:33,320 DEBUG SessionImpl:447 - transaction completion 16:10:33,320 DEBUG SessionImpl:435 - closing session 16:10:33,320 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,320 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,320 DEBUG SessionImpl:447 - transaction completion 16:10:33,320 DEBUG SessionImpl:413 - opened session 16:10:33,320 DEBUG JDBCTransaction:36 - begin 16:10:33,320 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,320 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,320 DEBUG SessionImpl:1270 - updating [eg.Blog#2] 16:10:33,320 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,320 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,380 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,380 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,380 DEBUG SessionImpl:1205 - saveOrUpdate() previously saved instance with id: 3 16:10:33,380 DEBUG SessionImpl:1270 - updating [eg.BlogItem#3] 16:10:33,380 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,380 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,380 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null 16:10:33,380 DEBUG SessionImpl:656 - saving [eg.BlogItem#<null>] 16:10:33,380 DEBUG EntityPersister:495 - Inserting entity: eg.BlogItem (native id) 16:10:33,380 DEBUG SessionImpl:2981 - running Session.finalize() 16:10:33,380 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,380 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) Hibernate: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) 16:10:33,380 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#null 16:10:33,380 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,380 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,380 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:33,380 DEBUG EntityPersister:528 - Natively generated identity: 4 16:10:33,380 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,380 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,380 DEBUG JDBCTransaction:54 - commit 16:10:33,380 DEBUG SessionImpl:2011 - flushing session 16:10:33,380 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,380 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,380 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,380 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,430 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,430 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,430 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,430 DEBUG SessionImpl:268 - Collection dirty: [eg.Blog.items#2] 16:10:33,430 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,430 DEBUG SessionImpl:2209 - Updating entity: [eg.Blog#2] 16:10:33,430 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#2], was: [eg.Blog.items#2] 16:10:33,430 DEBUG SessionImpl:2209 - Updating entity: [eg.BlogItem#3] 16:10:33,430 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,430 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,430 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 2 updates, 0 deletions to 3 objects 16:10:33,430 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 1 updates, 0 removals to 1 collections 16:10:33,430 DEBUG SessionImpl:2058 - executing flush 16:10:33,430 DEBUG EntityPersister:617 - Updating entity: eg.Blog#2 16:10:33,430 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,430 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOGS set NAME=? where BLOG_ID=? Hibernate: update BLOGS set NAME=? where BLOG_ID=? 16:10:33,430 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#2 16:10:33,430 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,430 DEBUG EntityPersister:617 - Updating entity: eg.BlogItem#3 16:10:33,430 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,430 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,430 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,430 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOG_ITEMS set TITLE=?, TEXT=?, DATE_TIME=?, BLOG_ID=? where BLOG_ITEM_ID=? Hibernate: update BLOG_ITEMS set TITLE=?, TEXT=?, DATE_TIME=?, BLOG_ID=? where BLOG_ITEM_ID=? 16:10:33,430 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#3 16:10:33,430 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,430 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,430 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,430 DEBUG SessionImpl:2428 - post flush 16:10:33,430 DEBUG SessionImpl:447 - transaction completion 16:10:33,490 DEBUG SessionImpl:435 - closing session 16:10:33,490 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,490 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,490 DEBUG SessionImpl:447 - transaction completion 16:10:33,490 DEBUG SessionImpl:413 - opened session 16:10:33,490 DEBUG JDBCTransaction:36 - begin 16:10:33,490 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,490 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,490 DEBUG SessionImpl:1328 - find: select blog.id, blog.name, count(blogItem) from Blog as blog left outer join blog.items as blogItem group by blog.name, blog.id order by max(blogItem.datetime) 16:10:33,490 DEBUG SessionImpl:2981 - running Session.finalize() 16:10:33,540 DEBUG QueryTranslator:144 - compiling query 16:10:33,650 DEBUG SessionImpl:2011 - flushing session 16:10:33,650 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,650 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,650 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,650 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects 16:10:33,650 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections 16:10:33,650 DEBUG SessionImpl:1573 - Dont need to execute flush 16:10:33,650 DEBUG QueryTranslator:200 - HQL: select blog.id, blog.name, count(blogItem) from eg.Blog as blog left outer join blog.items as blogItem group by blog.name, blog.id order by max(blogItem.datetime) 16:10:33,650 DEBUG QueryTranslator:201 - SQL: select blog0_.BLOG_ID as x0_0_, blog0_.NAME as x1_0_, count(items1_.BLOG_ITEM_ID) as x2_0_ from BLOGS blog0_ left outer join BLOG_ITEMS items1_ on blog0_.BLOG_ID=items1_.BLOG_ID group by blog0_.NAME , blog0_.BLOG_ID order by max(items1_.DATE_TIME) 16:10:33,710 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,710 DEBUG SessionFactoryImpl:526 - prepared statement get: select blog0_.BLOG_ID as x0_0_, blog0_.NAME as x1_0_, count(items1_.BLOG_ITEM_ID) as x2_0_ from BLOGS blog0_ left outer join BLOG_ITEMS items1_ on blog0_.BLOG_ID=items1_.BLOG_ID group by blog0_.NAME , blog0_.BLOG_ID order by max(items1_.DATE_TIME) limit ?, ? Hibernate: select blog0_.BLOG_ID as x0_0_, blog0_.NAME as x1_0_, count(items1_.BLOG_ITEM_ID) as x2_0_ from BLOGS blog0_ left outer join BLOG_ITEMS items1_ on blog0_.BLOG_ID=items1_.BLOG_ID group by blog0_.NAME , blog0_.BLOG_ID order by max(items1_.DATE_TIME) limit ?, ? 16:10:33,710 DEBUG JDBCExceptionReporter:36 - SQL Exception java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) 16:10:33,710 WARN JDBCExceptionReporter:38 - SQL Error: 1111, SQLState: S1000 16:10:33,710 ERROR JDBCExceptionReporter:46 - General error: Utilisation invalide de la clause GROUP 16:10:33,710 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,710 ERROR JDBCExceptionReporter:37 - Could not execute query java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) 16:10:33,760 DEBUG JDBCTransaction:77 - rollback 16:10:33,760 DEBUG SessionImpl:447 - transaction completion 16:10:33,760 DEBUG SessionImpl:435 - closing session 16:10:33,760 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,760 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,760 DEBUG SessionImpl:447 - transaction completion 16:10:33,760 DEBUG SessionImpl:2981 - running Session.finalize() java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) rethrown as net.sf.hibernate.JDBCException: Could not execute query: General error: Utilisation invalide de la clause GROUP at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1346) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) Caused by: java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) ... 4 more
    via by Laurent Bauchau,
  • I'm trying to run the sample from "Chapter 10. A Worked Example", i write additional code in the BlogMain class(rename as Main): public static void main(String[] args) { new Main(); } public Main() { try { configure(); exportTables(); Blog blog; for(int i=0;i<2;i++){ blog = createBlog("Blog_" + i); for(int j=0;j<2;j++){ createBlogItem(blog,"item_" + j,"blablabla hahaha tralala"); } } List list = listAllBlogNamesAndItemCounts(100); java.util.Iterator it = list.iterator(); while(it.hasNext()){ Object o = it.next(); System.out.println(o); } } catch (Exception e) { e.printStackTrace(); } } listAllBlogNamesAndItemCounts method throw an SQLException: Hibernate: select blog0_.BLOG_ID as x0_0_, blog0_.NAME as x1_0_, count(items1_.BLOG_ITEM_ID) as x2_0_ from BLOGS blog0_ left outer join BLOG_ITEMS items1_ on blog0_.BLOG_ID=items1_.BLOG_ID group by blog0_.NAME , blog0_.BLOG_ID order by max(items1_.DATE_TIME) limit ?, ? 16:10:33,710 DEBUG JDBCExceptionReporter:36 - SQL Exception java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) ######################## # hibernate.properties # ######################## hibernate.query.imports eg hibernate.dialect net.sf.hibernate.dialect.MySQLDialect hibernate.connection.driver_class org.gjt.mm.mysql.Driver #hibernate.connection.driver_class com.mysql.jdbc.Driver hibernate.connection.url jdbc:mysql:///hibernate hibernate.connection.username root hibernate.connection.password hibernate.connection.pool_size 1 hibernate.statement_cache.size 25 hibernate.show_sql true ######## # logs # ######## 16:10:28,320 INFO Environment:403 - Hibernate 2.0.3 16:10:28,380 INFO Environment:437 - loaded properties from resource hibernate.properties: {hibernate.connection.driver_class=org.gjt.mm.mysql.Driver, hibernate.cglib.use_reflection_optimizer=true, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, hibernate.query.imports=eg, hibernate.connection.username=root, hibernate.connection.url=jdbc:mysql:///hibernate, hibernate.show_sql=true, hibernate.connection.password=, hibernate.statement_cache.size=25, hibernate.connection.pool_size=1} 16:10:28,430 INFO Environment:452 - using CGLIB reflection optimizer 16:10:28,430 INFO Environment:462 - JVM proxy support: true 16:10:28,490 INFO Configuration:283 - Mapping resource: eg/Blog.hbm.xml 16:10:29,700 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 16:10:29,700 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath 16:10:30,080 INFO Binder:178 - Mapping class: eg.Blog -> BLOGS 16:10:30,250 DEBUG Binder:394 - Mapped property: id -> BLOG_ID, type: long 16:10:30,300 DEBUG Binder:394 - Mapped property: name -> NAME, type: string 16:10:30,360 DEBUG Binder:394 - Mapped property: items, type: java.util.Collection 16:10:30,360 INFO Configuration:283 - Mapping resource: eg/BlogItem.hbm.xml 16:10:30,360 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 16:10:30,360 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath 16:10:30,410 INFO Binder:178 - Mapping class: eg.BlogItem -> BLOG_ITEMS 16:10:30,410 DEBUG Binder:394 - Mapped property: id -> BLOG_ITEM_ID, type: long 16:10:30,410 DEBUG Binder:394 - Mapped property: title -> TITLE, type: string 16:10:30,460 DEBUG Binder:394 - Mapped property: text -> TEXT, type: string 16:10:30,460 DEBUG Binder:394 - Mapped property: datetime -> DATE_TIME, type: calendar 16:10:30,460 DEBUG Binder:394 - Mapped property: blog -> BLOG_ID, type: eg.Blog 16:10:30,460 INFO Configuration:492 - processing one-to-many association mappings 16:10:30,460 DEBUG Binder:1134 - Second pass for collection: eg.Blog.items 16:10:30,460 INFO Binder:1025 - Mapping collection: eg.Blog.items -> BLOG_ITEMS 16:10:30,520 DEBUG Binder:1146 - Mapped collection key: BLOG_ID, one-to-many: eg.BlogItem 16:10:30,520 INFO Configuration:503 - processing foreign key constraints 16:10:30,520 DEBUG Configuration:513 - resolving reference to class: eg.Blog 16:10:30,850 INFO SessionFactoryImpl:132 - building session factory 16:10:30,850 DEBUG SessionFactoryImpl:134 - instantiating session factory with properties: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, hibernate.connection.password=, sun.boot.library.path=C:\J2SDK1.4.2_01\JRE\bin, java.vm.version=1.4.2_01-b06, hibernate.connection.username=root, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=FR, sun.os.patch.level= A , java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\WINDOWS\Bureau\HibernateTest, java.runtime.version=1.4.2_01-b06, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\J2SDK1.4.2_01\JRE\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\WINDOWS\TEMP\, line.separator= , java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows 98, sun.java2d.fontpath=, java.library.path=C:\J2SDK1.4.2_01\BIN;.;C:\WINDOWS\SYSTEM;C:\WINDOWS;C:\JWSDP-1.2\JWSDP-SHARED\BIN;C:\WINDOWS;C:\WINDOWS\COMMAND;C:\J2SDK1.4.2_01\BIN, java.specification.name=Java Platform API Specification, java.class.version=48.0, hibernate.connection.pool_size=1, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, os.version=4.10, user.home=C:\WINDOWS, user.timezone=Europe/Paris, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.driver_class=org.gjt.mm.mysql.Driver, user.name=Laurent Bauchau, java.class.path=C:\WINDOWS\Bureau\HibernateTest\src;C:\hibernate-2.0.3\hibernate2.jar;C:\hibernate-2.0.3\lib\commons-lang.jar;C:\hibernate-2.0.3\lib\dom4j.jar;C:\hibernate-2.0.3\lib\commons-logging.jar;C:\hibernate-2.0.3\lib\commons-beanutils.jar;C:\hibernate-2.0.3\lib\commons-dbcp.jar;C:\hibernate-2.0.3\lib\commons-collections.jar;C:\hibernate-2.0.3\lib\commons-pool.jar;C:\hibernate-2.0.3\lib\xml-apis.jar;C:\hibernate-2.0.3\lib\c3p0.jar;C:\hibernate-2.0.3\lib\cglib-asm.jar;C:\hibernate-2.0.3\lib\connector.jar;C:\hibernate-2.0.3\lib\jaas.jar;C:\hibernate-2.0.3\lib\jcs.jar;C:\hibernate-2.0.3\lib\jdbc2_0-stdext.jar;C:\hibernate-2.0.3\lib\jta.jar;C:\hibernate-2.0.3\lib\junit.jar;C:\hibernate-2.0.3\lib\odmg.jar;C:\hibernate-2.0.3\lib\optional.jar;C:\hibernate-2.0.3\lib\proxool.jar;C:\hibernate-2.0.3\lib\xalan.jar;C:\hibernate-2.0.3\lib\xerces.jar;C:\hibernate-2.0.3\lib\ant.jar;E:\downloads\mm.mysql-2.0.14\mm.mysql-2.0.14-bin.jar;C:\jakarta-log4j-1.2.8\dist\lib\log4j-1.2.8.jar, hibernate.show_sql=true, java.vm.specification.version=1.0, sun.arch.data.model=32, java.home=C:\J2SDK1.4.2_01\JRE, hibernate.connection.url=jdbc:mysql:///hibernate, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, java.specification.vendor=Sun Microsystems Inc., user.language=fr, awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, hibernate.cglib.use_reflection_optimizer=true, java.version=1.4.2_01, java.ext.dirs=C:\J2SDK1.4.2_01\JRE\lib\ext, sun.boot.class.path=C:\J2SDK1.4.2_01\JRE\lib\rt.jar;C:\J2SDK1.4.2_01\JRE\lib\i18n.jar;C:\J2SDK1.4.2_01\JRE\lib\sunrsasign.jar;C:\J2SDK1.4.2_01\JRE\lib\jsse.jar;C:\J2SDK1.4.2_01\JRE\lib\jce.jar;C:\J2SDK1.4.2_01\JRE\lib\charsets.jar;C:\J2SDK1.4.2_01\JRE\classes, java.vendor=Sun Microsystems Inc., file.separator=\, hibernate.query.imports=eg, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, hibernate.statement_cache.size=25, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.isalist=pentium i486 i386} 16:10:30,900 INFO Dialect:83 - Using dialect: net.sf.hibernate.dialect.MySQLDialect 16:10:30,960 INFO DriverManagerConnectionProvider:41 - Hibernate connection pool size: 1 16:10:30,960 INFO DriverManagerConnectionProvider:70 - using driver: org.gjt.mm.mysql.Driver at URL: jdbc:mysql:///hibernate 16:10:30,960 INFO DriverManagerConnectionProvider:71 - connection properties: {user=root, password=} 16:10:30,960 INFO PreparedStatementCache:60 - prepared statement cache size: 25 16:10:31,010 INFO SessionFactoryImpl:162 - Use outer join fetching: true 16:10:31,010 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:31,010 DEBUG DriverManagerConnectionProvider:90 - opening new JDBC connection 16:10:31,230 DEBUG DriverManagerConnectionProvider:103 - created connection to: jdbc:mysql:///hibernate, Isolation Level: 2 16:10:31,230 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:31,230 INFO SessionFactoryImpl:185 - Use scrollable result sets: true 16:10:31,230 INFO SessionFactoryImpl:186 - JDBC 2 max batch size: 15 16:10:31,290 INFO SessionFactoryImpl:194 - echoing all SQL to stdout 16:10:32,110 DEBUG SessionFactoryObjectFactory:39 - initializing class SessionFactoryObjectFactory 16:10:32,170 DEBUG SessionFactoryObjectFactory:76 - registered: 4028ef83f869483a00f86948402e0000 (unnamed) 16:10:32,170 INFO SessionFactoryObjectFactory:82 - no JDNI name configured 16:10:32,170 INFO SessionFactoryImpl:269 - Query language substitutions: {} 16:10:32,170 DEBUG SessionFactoryImpl:281 - instantiated session factory 16:10:32,170 INFO Configuration:283 - Mapping resource: eg/Blog.hbm.xml 16:10:32,170 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 16:10:32,170 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath 16:10:32,220 INFO Binder:178 - Mapping class: eg.Blog -> BLOGS 16:10:32,220 DEBUG Binder:394 - Mapped property: id -> BLOG_ID, type: long 16:10:32,220 DEBUG Binder:394 - Mapped property: name -> NAME, type: string 16:10:32,220 DEBUG Binder:394 - Mapped property: items, type: java.util.Collection 16:10:32,280 INFO Configuration:283 - Mapping resource: eg/BlogItem.hbm.xml 16:10:32,280 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/ 16:10:32,280 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath 16:10:32,330 INFO Binder:178 - Mapping class: eg.BlogItem -> BLOG_ITEMS 16:10:32,330 DEBUG Binder:394 - Mapped property: id -> BLOG_ITEM_ID, type: long 16:10:32,330 DEBUG Binder:394 - Mapped property: title -> TITLE, type: string 16:10:32,330 DEBUG Binder:394 - Mapped property: text -> TEXT, type: string 16:10:32,330 DEBUG Binder:394 - Mapped property: datetime -> DATE_TIME, type: calendar 16:10:32,390 DEBUG Binder:394 - Mapped property: blog -> BLOG_ID, type: eg.Blog 16:10:32,390 INFO Dialect:83 - Using dialect: net.sf.hibernate.dialect.MySQLDialect 16:10:32,390 INFO Configuration:492 - processing one-to-many association mappings 16:10:32,390 DEBUG Binder:1134 - Second pass for collection: eg.Blog.items 16:10:32,390 INFO Binder:1025 - Mapping collection: eg.Blog.items -> BLOG_ITEMS 16:10:32,390 DEBUG Binder:1146 - Mapped collection key: BLOG_ID, one-to-many: eg.BlogItem 16:10:32,390 INFO Configuration:503 - processing foreign key constraints 16:10:32,390 DEBUG Configuration:513 - resolving reference to class: eg.Blog 16:10:32,390 INFO Configuration:492 - processing one-to-many association mappings 16:10:32,390 INFO Configuration:503 - processing foreign key constraints 16:10:32,390 INFO DriverManagerConnectionProvider:41 - Hibernate connection pool size: 1 16:10:32,390 INFO DriverManagerConnectionProvider:70 - using driver: org.gjt.mm.mysql.Driver at URL: jdbc:mysql:///hibernate 16:10:32,390 INFO DriverManagerConnectionProvider:71 - connection properties: {user=root, password=} 16:10:32,440 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:32,440 DEBUG DriverManagerConnectionProvider:90 - opening new JDBC connection 16:10:32,500 DEBUG DriverManagerConnectionProvider:103 - created connection to: jdbc:mysql:///hibernate, Isolation Level: 2 drop table BLOGS drop table BLOG_ITEMS create table BLOGS ( BLOG_ID BIGINT NOT NULL AUTO_INCREMENT, NAME VARCHAR(255) not null unique, primary key (BLOG_ID) ) create table BLOG_ITEMS ( BLOG_ITEM_ID BIGINT NOT NULL AUTO_INCREMENT, TITLE VARCHAR(255) not null, TEXT VARCHAR(255) not null, DATE_TIME DATETIME not null, BLOG_ID BIGINT not null, primary key (BLOG_ITEM_ID) ) alter table BLOG_ITEMS add index (BLOG_ID), add constraint FK5FDFB8C329868AD8 foreign key (BLOG_ID) references BLOGS (BLOG_ID) 16:10:32,550 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:32,550 INFO DriverManagerConnectionProvider:144 - cleaning up connection pool: jdbc:mysql:///hibernate 16:10:32,610 INFO DriverManagerConnectionProvider:144 - cleaning up connection pool: jdbc:mysql:///hibernate 16:10:32,660 DEBUG SessionImpl:413 - opened session 16:10:32,660 DEBUG JDBCTransaction:36 - begin 16:10:32,660 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:32,660 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:32,660 DEBUG SessionImpl:656 - saving [eg.Blog#<null>] 16:10:32,660 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,660 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,660 DEBUG SessionImpl:2520 - Wrapped collection in role: eg.Blog.items 16:10:32,720 DEBUG EntityPersister:495 - Inserting entity: eg.Blog (native id) 16:10:32,720 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,720 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOGS (NAME) values (?) Hibernate: insert into BLOGS (NAME) values (?) 16:10:32,770 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#null 16:10:32,770 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,830 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,830 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:32,830 DEBUG EntityPersister:528 - Natively generated identity: 1 16:10:32,830 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,830 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,830 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:32,830 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,830 DEBUG JDBCTransaction:54 - commit 16:10:32,830 DEBUG SessionImpl:2011 - flushing session 16:10:32,830 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,830 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:32,830 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,830 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:32,880 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#1], was: [<unreferenced>] 16:10:32,880 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:32,880 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:32,880 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects 16:10:32,880 DEBUG SessionImpl:2028 - Flushed: 1 (re)creations, 0 updates, 0 removals to 1 collections 16:10:32,880 DEBUG SessionImpl:2058 - executing flush 16:10:32,880 DEBUG SessionImpl:2428 - post flush 16:10:32,880 DEBUG SessionImpl:447 - transaction completion 16:10:32,880 DEBUG SessionImpl:435 - closing session 16:10:32,880 DEBUG SessionImpl:2930 - disconnecting session 16:10:32,880 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:32,880 DEBUG SessionImpl:447 - transaction completion 16:10:32,880 DEBUG SessionImpl:413 - opened session 16:10:32,880 DEBUG JDBCTransaction:36 - begin 16:10:32,880 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:32,880 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:32,880 DEBUG SessionImpl:1270 - updating [eg.Blog#1] 16:10:32,880 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,880 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:32,880 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:32,880 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:32,940 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null 16:10:32,940 DEBUG SessionImpl:656 - saving [eg.BlogItem#<null>] 16:10:32,940 DEBUG EntityPersister:495 - Inserting entity: eg.BlogItem (native id) 16:10:32,940 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,940 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) Hibernate: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) 16:10:32,940 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#null 16:10:32,940 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,940 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,940 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:32,940 DEBUG EntityPersister:528 - Natively generated identity: 1 16:10:32,940 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,940 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,940 DEBUG JDBCTransaction:54 - commit 16:10:32,940 DEBUG SessionImpl:2011 - flushing session 16:10:32,940 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:32,940 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:32,940 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:32,940 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:32,940 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:32,940 DEBUG SessionImpl:268 - Collection dirty: [eg.Blog.items#1] 16:10:32,940 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:32,990 DEBUG SessionImpl:2209 - Updating entity: [eg.Blog#1] 16:10:32,990 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#1], was: [eg.Blog.items#1] 16:10:32,990 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:32,990 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:32,990 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 1 updates, 0 deletions to 2 objects 16:10:32,990 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 1 updates, 0 removals to 1 collections 16:10:32,990 DEBUG SessionImpl:2058 - executing flush 16:10:32,990 DEBUG EntityPersister:617 - Updating entity: eg.Blog#1 16:10:32,990 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:32,990 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOGS set NAME=? where BLOG_ID=? Hibernate: update BLOGS set NAME=? where BLOG_ID=? 16:10:32,990 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#1 16:10:32,990 DEBUG BatcherImpl:24 - Adding to batch 16:10:32,990 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:32,990 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:32,990 DEBUG SessionImpl:2428 - post flush 16:10:32,990 DEBUG SessionImpl:447 - transaction completion 16:10:32,990 DEBUG SessionImpl:435 - closing session 16:10:32,990 DEBUG SessionImpl:2930 - disconnecting session 16:10:32,990 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:32,990 DEBUG SessionImpl:447 - transaction completion 16:10:33,050 DEBUG SessionImpl:413 - opened session 16:10:33,050 DEBUG JDBCTransaction:36 - begin 16:10:33,050 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,050 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,050 DEBUG SessionImpl:1270 - updating [eg.Blog#1] 16:10:33,050 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,050 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,050 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,050 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,050 DEBUG SessionImpl:1205 - saveOrUpdate() previously saved instance with id: 1 16:10:33,050 DEBUG SessionImpl:1270 - updating [eg.BlogItem#1] 16:10:33,050 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,050 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,050 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null 16:10:33,050 DEBUG SessionImpl:2981 - running Session.finalize() 16:10:33,050 DEBUG SessionImpl:656 - saving [eg.BlogItem#<null>] 16:10:33,050 DEBUG EntityPersister:495 - Inserting entity: eg.BlogItem (native id) 16:10:33,050 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,050 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) Hibernate: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) 16:10:33,050 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#null 16:10:33,050 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,050 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,050 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:33,050 DEBUG EntityPersister:528 - Natively generated identity: 2 16:10:33,050 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,050 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,100 DEBUG JDBCTransaction:54 - commit 16:10:33,100 DEBUG SessionImpl:2011 - flushing session 16:10:33,100 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,100 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,100 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,100 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,100 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,100 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,100 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,100 DEBUG SessionImpl:268 - Collection dirty: [eg.Blog.items#1] 16:10:33,100 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,100 DEBUG SessionImpl:2209 - Updating entity: [eg.Blog#1] 16:10:33,100 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#1], was: [eg.Blog.items#1] 16:10:33,100 DEBUG SessionImpl:2209 - Updating entity: [eg.BlogItem#1] 16:10:33,100 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,100 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,100 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 2 updates, 0 deletions to 3 objects 16:10:33,100 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 1 updates, 0 removals to 1 collections 16:10:33,100 DEBUG SessionImpl:2058 - executing flush 16:10:33,100 DEBUG EntityPersister:617 - Updating entity: eg.Blog#1 16:10:33,100 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,100 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOGS set NAME=? where BLOG_ID=? Hibernate: update BLOGS set NAME=? where BLOG_ID=? 16:10:33,100 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#1 16:10:33,100 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,100 DEBUG EntityPersister:617 - Updating entity: eg.BlogItem#1 16:10:33,100 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,100 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,100 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,100 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOG_ITEMS set TITLE=?, TEXT=?, DATE_TIME=?, BLOG_ID=? where BLOG_ITEM_ID=? Hibernate: update BLOG_ITEMS set TITLE=?, TEXT=?, DATE_TIME=?, BLOG_ID=? where BLOG_ITEM_ID=? 16:10:33,100 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#1 16:10:33,100 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,100 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,160 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,160 DEBUG SessionImpl:2428 - post flush 16:10:33,160 DEBUG SessionImpl:447 - transaction completion 16:10:33,160 DEBUG SessionImpl:435 - closing session 16:10:33,160 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,160 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,160 DEBUG SessionImpl:447 - transaction completion 16:10:33,160 DEBUG SessionImpl:413 - opened session 16:10:33,160 DEBUG JDBCTransaction:36 - begin 16:10:33,160 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,160 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,160 DEBUG SessionImpl:656 - saving [eg.Blog#<null>] 16:10:33,160 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,160 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,160 DEBUG SessionImpl:2520 - Wrapped collection in role: eg.Blog.items 16:10:33,160 DEBUG EntityPersister:495 - Inserting entity: eg.Blog (native id) 16:10:33,160 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,160 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOGS (NAME) values (?) Hibernate: insert into BLOGS (NAME) values (?) 16:10:33,160 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#null 16:10:33,160 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,160 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,160 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:33,160 DEBUG EntityPersister:528 - Natively generated identity: 2 16:10:33,160 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,210 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,210 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,210 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,210 DEBUG JDBCTransaction:54 - commit 16:10:33,210 DEBUG SessionImpl:2011 - flushing session 16:10:33,210 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,210 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,210 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,210 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,210 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#2], was: [<unreferenced>] 16:10:33,210 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,210 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,210 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects 16:10:33,210 DEBUG SessionImpl:2028 - Flushed: 1 (re)creations, 0 updates, 0 removals to 1 collections 16:10:33,210 DEBUG SessionImpl:2058 - executing flush 16:10:33,210 DEBUG SessionImpl:2428 - post flush 16:10:33,210 DEBUG SessionImpl:447 - transaction completion 16:10:33,210 DEBUG SessionImpl:435 - closing session 16:10:33,210 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,210 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,210 DEBUG SessionImpl:447 - transaction completion 16:10:33,210 DEBUG SessionImpl:413 - opened session 16:10:33,210 DEBUG JDBCTransaction:36 - begin 16:10:33,210 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,210 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,210 DEBUG SessionImpl:1270 - updating [eg.Blog#2] 16:10:33,210 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,210 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,270 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,270 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,270 DEBUG SessionImpl:2981 - running Session.finalize() 16:10:33,270 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null 16:10:33,270 DEBUG SessionImpl:656 - saving [eg.BlogItem#<null>] 16:10:33,270 DEBUG EntityPersister:495 - Inserting entity: eg.BlogItem (native id) 16:10:33,270 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,270 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) Hibernate: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) 16:10:33,270 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#null 16:10:33,270 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,270 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,270 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:33,270 DEBUG EntityPersister:528 - Natively generated identity: 3 16:10:33,270 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,270 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,270 DEBUG JDBCTransaction:54 - commit 16:10:33,270 DEBUG SessionImpl:2011 - flushing session 16:10:33,270 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,270 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,320 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,320 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,320 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,320 DEBUG SessionImpl:268 - Collection dirty: [eg.Blog.items#2] 16:10:33,320 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,320 DEBUG SessionImpl:2209 - Updating entity: [eg.Blog#2] 16:10:33,320 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#2], was: [eg.Blog.items#2] 16:10:33,320 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,320 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,320 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 1 updates, 0 deletions to 2 objects 16:10:33,320 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 1 updates, 0 removals to 1 collections 16:10:33,320 DEBUG SessionImpl:2058 - executing flush 16:10:33,320 DEBUG EntityPersister:617 - Updating entity: eg.Blog#2 16:10:33,320 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,320 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOGS set NAME=? where BLOG_ID=? Hibernate: update BLOGS set NAME=? where BLOG_ID=? 16:10:33,320 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#2 16:10:33,320 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,320 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,320 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,320 DEBUG SessionImpl:2428 - post flush 16:10:33,320 DEBUG SessionImpl:447 - transaction completion 16:10:33,320 DEBUG SessionImpl:435 - closing session 16:10:33,320 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,320 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,320 DEBUG SessionImpl:447 - transaction completion 16:10:33,320 DEBUG SessionImpl:413 - opened session 16:10:33,320 DEBUG JDBCTransaction:36 - begin 16:10:33,320 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,320 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,320 DEBUG SessionImpl:1270 - updating [eg.Blog#2] 16:10:33,320 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,320 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,380 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,380 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,380 DEBUG SessionImpl:1205 - saveOrUpdate() previously saved instance with id: 3 16:10:33,380 DEBUG SessionImpl:1270 - updating [eg.BlogItem#3] 16:10:33,380 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,380 DEBUG Cascades:237 - unsaved-value strategy NULL 16:10:33,380 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null 16:10:33,380 DEBUG SessionImpl:656 - saving [eg.BlogItem#<null>] 16:10:33,380 DEBUG EntityPersister:495 - Inserting entity: eg.BlogItem (native id) 16:10:33,380 DEBUG SessionImpl:2981 - running Session.finalize() 16:10:33,380 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,380 DEBUG SessionFactoryImpl:526 - prepared statement get: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) Hibernate: insert into BLOG_ITEMS (TITLE, TEXT, DATE_TIME, BLOG_ID) values (?, ?, ?, ?) 16:10:33,380 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#null 16:10:33,380 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,380 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,380 DEBUG SessionFactoryImpl:526 - prepared statement get: SELECT LAST_INSERT_ID() Hibernate: SELECT LAST_INSERT_ID() 16:10:33,380 DEBUG EntityPersister:528 - Natively generated identity: 4 16:10:33,380 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,380 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,380 DEBUG JDBCTransaction:54 - commit 16:10:33,380 DEBUG SessionImpl:2011 - flushing session 16:10:33,380 DEBUG Cascades:336 - processing cascades for: eg.Blog 16:10:33,380 DEBUG Cascades:275 - cascading to collection: eg.Blog.items 16:10:33,380 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,380 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,430 DEBUG Cascades:87 - cascading to saveOrUpdate() 16:10:33,430 DEBUG SessionImpl:1183 - saveOrUpdate() persistent instance 16:10:33,430 DEBUG Cascades:344 - done processing cascades for: eg.Blog 16:10:33,430 DEBUG SessionImpl:268 - Collection dirty: [eg.Blog.items#2] 16:10:33,430 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,430 DEBUG SessionImpl:2209 - Updating entity: [eg.Blog#2] 16:10:33,430 DEBUG SessionImpl:2550 - Collection found: [eg.Blog.items#2], was: [eg.Blog.items#2] 16:10:33,430 DEBUG SessionImpl:2209 - Updating entity: [eg.BlogItem#3] 16:10:33,430 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,430 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,430 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 2 updates, 0 deletions to 3 objects 16:10:33,430 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 1 updates, 0 removals to 1 collections 16:10:33,430 DEBUG SessionImpl:2058 - executing flush 16:10:33,430 DEBUG EntityPersister:617 - Updating entity: eg.Blog#2 16:10:33,430 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,430 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOGS set NAME=? where BLOG_ID=? Hibernate: update BLOGS set NAME=? where BLOG_ID=? 16:10:33,430 DEBUG EntityPersister:366 - Dehydrating entity: eg.Blog#2 16:10:33,430 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,430 DEBUG EntityPersister:617 - Updating entity: eg.BlogItem#3 16:10:33,430 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,430 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,430 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,430 DEBUG SessionFactoryImpl:526 - prepared statement get: update BLOG_ITEMS set TITLE=?, TEXT=?, DATE_TIME=?, BLOG_ID=? where BLOG_ITEM_ID=? Hibernate: update BLOG_ITEMS set TITLE=?, TEXT=?, DATE_TIME=?, BLOG_ID=? where BLOG_ITEM_ID=? 16:10:33,430 DEBUG EntityPersister:366 - Dehydrating entity: eg.BlogItem#3 16:10:33,430 DEBUG BatcherImpl:24 - Adding to batch 16:10:33,430 DEBUG BatcherImpl:46 - Executing batch size: 1 16:10:33,430 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,430 DEBUG SessionImpl:2428 - post flush 16:10:33,430 DEBUG SessionImpl:447 - transaction completion 16:10:33,490 DEBUG SessionImpl:435 - closing session 16:10:33,490 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,490 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,490 DEBUG SessionImpl:447 - transaction completion 16:10:33,490 DEBUG SessionImpl:413 - opened session 16:10:33,490 DEBUG JDBCTransaction:36 - begin 16:10:33,490 DEBUG DriverManagerConnectionProvider:77 - total checked-out connections: 0 16:10:33,490 DEBUG DriverManagerConnectionProvider:83 - using pooled JDBC connection, pool size: 0 16:10:33,490 DEBUG SessionImpl:1328 - find: select blog.id, blog.name, count(blogItem) from Blog as blog left outer join blog.items as blogItem group by blog.name, blog.id order by max(blogItem.datetime) 16:10:33,490 DEBUG SessionImpl:2981 - running Session.finalize() 16:10:33,540 DEBUG QueryTranslator:144 - compiling query 16:10:33,650 DEBUG SessionImpl:2011 - flushing session 16:10:33,650 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections 16:10:33,650 DEBUG SessionImpl:2397 - Processing unreferenced collections 16:10:33,650 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates 16:10:33,650 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects 16:10:33,650 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections 16:10:33,650 DEBUG SessionImpl:1573 - Dont need to execute flush 16:10:33,650 DEBUG QueryTranslator:200 - HQL: select blog.id, blog.name, count(blogItem) from eg.Blog as blog left outer join blog.items as blogItem group by blog.name, blog.id order by max(blogItem.datetime) 16:10:33,650 DEBUG QueryTranslator:201 - SQL: select blog0_.BLOG_ID as x0_0_, blog0_.NAME as x1_0_, count(items1_.BLOG_ITEM_ID) as x2_0_ from BLOGS blog0_ left outer join BLOG_ITEMS items1_ on blog0_.BLOG_ID=items1_.BLOG_ID group by blog0_.NAME , blog0_.BLOG_ID order by max(items1_.DATE_TIME) 16:10:33,710 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets 16:10:33,710 DEBUG SessionFactoryImpl:526 - prepared statement get: select blog0_.BLOG_ID as x0_0_, blog0_.NAME as x1_0_, count(items1_.BLOG_ITEM_ID) as x2_0_ from BLOGS blog0_ left outer join BLOG_ITEMS items1_ on blog0_.BLOG_ID=items1_.BLOG_ID group by blog0_.NAME , blog0_.BLOG_ID order by max(items1_.DATE_TIME) limit ?, ? Hibernate: select blog0_.BLOG_ID as x0_0_, blog0_.NAME as x1_0_, count(items1_.BLOG_ITEM_ID) as x2_0_ from BLOGS blog0_ left outer join BLOG_ITEMS items1_ on blog0_.BLOG_ID=items1_.BLOG_ID group by blog0_.NAME , blog0_.BLOG_ID order by max(items1_.DATE_TIME) limit ?, ? 16:10:33,710 DEBUG JDBCExceptionReporter:36 - SQL Exception java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) 16:10:33,710 WARN JDBCExceptionReporter:38 - SQL Error: 1111, SQLState: S1000 16:10:33,710 ERROR JDBCExceptionReporter:46 - General error: Utilisation invalide de la clause GROUP 16:10:33,710 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets 16:10:33,710 ERROR JDBCExceptionReporter:37 - Could not execute query java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) 16:10:33,760 DEBUG JDBCTransaction:77 - rollback 16:10:33,760 DEBUG SessionImpl:447 - transaction completion 16:10:33,760 DEBUG SessionImpl:435 - closing session 16:10:33,760 DEBUG SessionImpl:2930 - disconnecting session 16:10:33,760 DEBUG DriverManagerConnectionProvider:117 - returning connection to pool, pool size: 1 16:10:33,760 DEBUG SessionImpl:447 - transaction completion 16:10:33,760 DEBUG SessionImpl:2981 - running Session.finalize() java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) rethrown as net.sf.hibernate.JDBCException: Could not execute query: General error: Utilisation invalide de la clause GROUP at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1346) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) Caused by: java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) ... 4 more
    via by Laurent Bauchau,
  • Problems with Hibernate
    via springframework by *anonymous
    ,
  • JDBC problem
    via by ukal...@hotmail.com,
  • JDBC problem
    via by ukal...@my-deja.com,
    • net.sf.hibernate.JDBCException: Could not execute query: General error: Utilisation invalide de la clause GROUP at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1346) at net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76) at eg.Main.listAllBlogNamesAndItemCounts(Main.java:193) at eg.Main.<init>(Main.java:36) at eg.Main.main(Main.java:18) Caused by: java.sql.SQLException: General error: Utilisation invalide de la clause GROUP at org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source) at org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source) at org.gjt.mm.mysql.Connection.execSQL(Unknown Source) at org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source) at net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71) at net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551) at net.sf.hibernate.loader.Loader.doFind(Loader.java:140) at net.sf.hibernate.loader.Loader.find(Loader.java:620) at net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928) at net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343) ... 4 more

    Users with the same issue

    Unknown visitor1 times, last one,