net.sf.hibernate.JDBCException: Could not execute query: General error: Utilisation invalide de la clause GROUP

Hibernate JIRA | Laurent Bauchau | 1 decade ago
  1. 0

    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

    Hibernate JIRA | 1 decade ago | Laurent Bauchau
    net.sf.hibernate.JDBCException: Could not execute query: General error: Utilisation invalide de la clause GROUP
  2. 0

    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

    Hibernate JIRA | 1 decade ago | Laurent Bauchau
    net.sf.hibernate.JDBCException: Could not execute query: General error: Utilisation invalide de la clause GROUP
  3. 0

    Hibernate: Communication link failure: java.io.IOException | Aaron Johnson

    cephas.net | 3 months ago
    java.sql.SQLException: Communication link failure: java.io.IOException
  4. Speed up your debug routine!

    Automated exception search integrated into your IDE

  5. 0

    Problems with Hibernate

    springframework | 1 decade ago | *anonymous
    java.sql.SQLException: Syntax error or access violation: You have an error in your SQL syntax.  Check the manual that corresponds to your MySQL server version for the right syntax to use near 'double from PRODUCTS product' at line 1
  6. 0

    java.sql.SQLException: Communication link failure from Resin MySQL Servlet

    Google Groups | 2 decades ago | Krazykuo
    java.sql.SQLException: Communication link failure: java.io.IOException

    Not finding the right solution?
    Take a tour to get the most out of Samebug.

    Tired of useless tips?

    Automated exception search integrated into your IDE

    Root Cause Analysis

    1. java.sql.SQLException

      General error: Utilisation invalide de la clause GROUP

      at org.gjt.mm.mysql.MysqlIO.sendCommand()
    2. MySQL jdbc
      PreparedStatement.executeQuery
      1. org.gjt.mm.mysql.MysqlIO.sendCommand(Unknown Source)
      2. org.gjt.mm.mysql.MysqlIO.sqlQueryDirect(Unknown Source)
      3. org.gjt.mm.mysql.Connection.execSQL(Unknown Source)
      4. org.gjt.mm.mysql.PreparedStatement.executeQuery(Unknown Source)
      4 frames
    3. net.sf.hibernate
      QueryImpl.list
      1. net.sf.hibernate.impl.BatcherImpl.getResultSet(BatcherImpl.java:71)
      2. net.sf.hibernate.loader.Loader.getResultSet(Loader.java:551)
      3. net.sf.hibernate.loader.Loader.doFind(Loader.java:140)
      4. net.sf.hibernate.loader.Loader.find(Loader.java:620)
      5. net.sf.hibernate.hql.QueryTranslator.find(QueryTranslator.java:928)
      6. net.sf.hibernate.impl.SessionImpl.find(SessionImpl.java:1343)
      7. net.sf.hibernate.impl.QueryImpl.list(QueryImpl.java:76)
      7 frames
    4. FitNesse
      Main.main
      1. eg.Main.listAllBlogNamesAndItemCounts(Main.java:193)
      2. eg.Main.<init>(Main.java:36)
      3. eg.Main.main(Main.java:18)
      3 frames