Details

    • Type: Bug
    • Status: Closed
    • Priority: Major
    • Resolution: Rejected
    • Affects Version/s: 2.0.3
    • Fix Version/s: None
    • Component/s: core
    • Labels:
      None
    • Environment:
      win98, 2sdk1.4.2_01, MySQL 4.0.15-max-debug
    • Bug Testcase Reminder (view):

      Bug reports should generally be accompanied by a test case!

    • Last commented by a user?:
      true

      Description

      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)

      ########################

      1. 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

      ########

      1. 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

        Attachments

          Activity

            People

            • Votes:
              0 Vote for this issue
              Watchers:
              0 Start watching this issue

              Dates

              • Created:
                Updated:
                Resolved: