why hibernate genarate bad sql ? ( "," after table name in update sql query )
is this a bug or my mistake ?


Hibernate version: 3.1.3

Mapping documents:

<class name="com.comarch.wfm.daohibernate.GroupHVO" table="tWFMGrpGroupsHierarchy">
<id name="id" column="id" type="long">
<generator class="native">
<param name="sequence">tWFMGrpGroupsHierarchy_id_SEQ</param>
</generator>
</id>

<many-to-one name="group" class="com.comarch.wfm.daohibernate.GroupVO" column="group_Id">
</many-to-one>

<property name="hlevel" type="long" column="hlevel">
</property>

<many-to-one name="parentGroup" class="com.comarch.wfm.daohibernate.GroupVO" column="parentGroup_Id">
</many-to-one>

<property name="nullLevel" type="long" column="nulllevel">
</property>

<many-to-one name="path" class="com.comarch.wfm.daohibernate.GroupHPVO" column="path_Id">
</many-to-one>

<many-to-one name="dayDeleted" class="com.comarch.wfm.daohibernate.DayVO" column="DayDeleted_Id">
</many-to-one>

<property name="atime" type="timestamp" column="atime" not-null="true">
</property>

<property name="ctx_id" type="long" column="ctx_id" not-null="true">
</property>

<property name="mtx_id" type="long" column="mtx_id">
</property>
</class>



<class name="com.comarch.wfm.daohibernate.GroupVO" table="tWFMGrpGroups">
<id name="id" column="id" type="long">
<generator class="native">
<param name="sequence">tWFMGrpGroups_id_SEQ</param>
</generator>
</id>
<map name="children" table="tWFMGrpGroupHierarchy">
<key column="ParentGroup_Id">
</key>
<index column="Id" type="long">
</index>
<one-to-many class="com.comarch.wfm.daohibernate.GroupHVO" />
</map>
<many-to-one name="groupType" class="com.comarch.wfm.daohibernate.GroupTypeVO" column="GroupType_Id">
</many-to-one>
<property name="name" type="java.lang.String" column="name" not-null="true">
</property>
<map name="parents" table="tWFMGrpGroupsHierarchy" inverse="true" order-by="hlevel">
<key column="Group_Id">
</key>
<index column="Id" type="long">
</index>
<one-to-many class="com.comarch.wfm.daohibernate.GroupHVO" />
</map>
<map name="resources" table="tWFMGrpGroupResources" inverse="true">
<key column="group_Id">
</key>
<index column="Id" type="long">
</index>
<one-to-many class="com.comarch.wfm.daohibernate.GroupResourceVO" />
</map>
<map name="groupWorkCycles" table="tWFMCalGroupWorkCycles" inverse="true">
<key column="WorkCycle_Id">
</key>
<index column="Id" type="long">
</index>
<one-to-many class="com.comarch.wfm.daohibernate.GroupWorkCycleVO" />
</map>

<property name="description" type="string" column="description">
</property>

<many-to-one name="dayDeleted" class="com.comarch.wfm.daohibernate.DayVO" column="DayDeleted_Id">
</many-to-one>

<property name="atime" type="timestamp" column="atime" not-null="true">
</property>

<property name="ctx_id" type="long" column="ctx_id" not-null="true">
</property>

<property name="mtx_id" type="long" column="mtx_id">
</property>

</class>



<class name="com.comarch.wfm.daohibernate.GroupHPVO" table="twfmGrpGroupHierarchyPaths">

<id name="id" column="id" type="long">
<generator class="native">
<param name="sequence">twfmGrpGroupHierarchyPa_id_SEQ</param>
</generator>
</id>

<many-to-one name="groupAssignation" class="com.comarch.wfm.daohibernate.GroupHAVO" column="GroupAssignation_Id">
</many-to-one>

<map name="groupHVOMap" table="twfmGrpGroupHierarchy">
<key column="path_Id">
</key>
<index column="Id" type="long">
</index>
<one-to-many class="com.comarch.wfm.daohibernate.GroupHVO" />
</map>

<many-to-one name="group" class="com.comarch.wfm.daohibernate.GroupVO" column="group_id">
</many-to-one>

<many-to-one name="dayFrom" class="com.comarch.wfm.daohibernate.DayVO" column="DayFrom_Id">
</many-to-one>

<many-to-one name="dayTo" class="com.comarch.wfm.daohibernate.DayVO" column="DayTo_Id">
</many-to-one>

<many-to-one name="dayDeleted" class="com.comarch.wfm.daohibernate.DayVO" column="DayDeleted_Id">
</many-to-one>

<property name="atime" type="timestamp" column="atime" not-null="true">
</property>

<property name="ctx_id" type="long" column="ctx_id" not-null="true">
</property>

<property name="mtx_id" type="long" column="mtx_id">
</property>

</class>


Code between sessionFactory.openSession() and session.close():


Session session = tx.getSession(); // this open session
DayVO today = (DayVO)session.get(DayVO.class, new Long(30));
query = tx.getSession().createQuery(
"update GroupHVO " +
" SET " +
" dayDeleted = :today, " +
" atime = :sysdat, " +
" mtx_id = :mtx " +
" where " +
" path.dayFrom.id > :todayId and " +
" group.id = :groupId and " +
" dayDeleted is null");

query.setParameter("today", today);
query.setParameter("todayId", today.getId());
query.setParameter("sysdat", DAOUtils.getCurrentDate());
query.setParameter("mtx", new Long(1));
query.setParameter("groupId", new Long(18));
query.executeUpdate();

session.getTransaction().commit();
session.close();


Full stack trace of any exception that occurs:

Hibernate: select dayvo0_.id as id0_0_, dayvo0_.DayDate as DayDate0_0_, dayvo0_.atime as atime0_0_, dayvo0_.ctx_id as ctx4_0_0_, dayvo0_.mtx_id as mtx5_0_0_ from tWFMCalDays dayvo0_ where dayvo0_.id=?
Hibernate: update tWFMGrpGroupsHierarchy, set DayDeleted_Id=?, atime=?, mtx_id=? where DayFrom_Id>? and group_Id=? and (DayDeleted_Id is null)
Exception in thread "main" org.hibernate.exception.SQLGrammarException: could not execute update query
at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:67)
at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
at org.hibernate.hql.ast.exec.BasicExecutor.execute(BasicExecutor.java:84)
at org.hibernate.hql.ast.QueryTranslatorImpl.executeUpdate(QueryTranslatorImpl.java:334)
at org.hibernate.engine.query.HQLQueryPlan.performExecuteUpdate(HQLQueryPlan.java:209)
at org.hibernate.impl.SessionImpl.executeUpdate(SessionImpl.java:1126)
at org.hibernate.impl.QueryImpl.executeUpdate(QueryImpl.java:94)
at dupa.A001.main(A001.java:102)
Caused by: java.sql.SQLException: ORA-00971: missing SET keyword

at oracle.jdbc.dbaccess.DBError.throwSqlException(DBError.java:134)
at oracle.jdbc.oci8.OCIDBAccess.check_error(OCIDBAccess.java:2355)
at oracle.jdbc.oci8.OCIDBAccess.executeFetch(OCIDBAccess.java:1760)
at oracle.jdbc.oci8.OCIDBAccess.parseExecuteFetch(OCIDBAccess.java:1921)
at oracle.jdbc.driver.OracleStatement.executeNonQuery(OracleStatement.java:2152)
at oracle.jdbc.driver.OracleStatement.doExecuteOther(OracleStatement.java:2035)
at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:2876)
at oracle.jdbc.driver.OraclePreparedStatement.executeUpdate(OraclePreparedStatement.java:609)
at org.hibernate.hql.ast.exec.BasicExecutor.execute(BasicExecutor.java:75)
... 5 more



Name and version of the database you are using:

oracle 10g

The generated SQL (show_sql=true):

update tWFMGrpGroupsHierarchy, set DayDeleted_Id=?, atime=?, mtx_id=? where DayFrom_Id>? and group_Id=? and (DayDeleted_Id is null)

Debug level Hibernate log excerpt:


2006-07-19 15:06:26,750 DEBUG [org.hibernate.impl.SessionFactoryObjectFactory] initializing class SessionFactoryObjectFactory
2006-07-19 15:06:26,750 DEBUG [org.hibernate.impl.SessionFactoryObjectFactory] registered: 8a04aa050c86e4e9010c86e4f33e0000 (unnamed)
2006-07-19 15:06:26,750 INFO [org.hibernate.impl.SessionFactoryObjectFactory] Not binding factory to JNDI, no JNDI name configured
2006-07-19 15:06:26,750 DEBUG [org.hibernate.impl.SessionFactoryImpl] instantiated session factory
2006-07-19 15:06:26,750 DEBUG [org.hibernate.impl.SessionFactoryImpl] Checking 0 named HQL queries
2006-07-19 15:06:26,750 DEBUG [org.hibernate.impl.SessionFactoryImpl] Checking 0 named SQL queries
2006-07-19 15:06:26,828 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 4723975728128000
2006-07-19 15:06:26,828 DEBUG [org.hibernate.transaction.JDBCTransaction] begin
2006-07-19 15:06:26,828 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection
2006-07-19 15:06:26,828 DEBUG [org.hibernate.connection.DriverManagerConnectionProvider] total checked-out connections: 0
2006-07-19 15:06:26,828 DEBUG [org.hibernate.connection.DriverManagerConnectionProvider] using pooled JDBC connection, pool size: 0
2006-07-19 15:06:26,828 DEBUG [org.hibernate.transaction.JDBCTransaction] current autocommit status: false
2006-07-19 15:06:26,828 DEBUG [org.hibernate.jdbc.JDBCContext] after transaction begin
2006-07-19 15:06:26,859 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-07-19 15:06:26,859 DEBUG [org.hibernate.SQL] select this_.id as id0_0_, this_.DayDate as DayDate0_0_, this_.atime as atime0_0_, this_.ctx_id as ctx4_0_0_, this_.mtx_id as mtx5_0_0_ from tWFMCalDays this_ where this_.DayDate=?
2006-07-19 15:06:26,859 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2006-07-19 15:06:26,906 DEBUG [org.hibernate.type.DateType] binding '06 stycze˝ 2001' to parameter: 1
2006-07-19 15:06:26,921 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2006-07-19 15:06:26,937 DEBUG [org.hibernate.loader.Loader] processing result set
2006-07-19 15:06:26,937 DEBUG [org.hibernate.loader.Loader] result set row: 0
2006-07-19 15:06:26,984 DEBUG [org.hibernate.type.LongType] returning '6' as column: id0_0_
2006-07-19 15:06:26,984 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.comarch.wfm.daohibernate.DayVO#6]
2006-07-19 15:06:26,984 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.comarch.wfm.daohibernate.DayVO#6]
2006-07-19 15:06:27,000 DEBUG [org.hibernate.persister.entity.AbstractEntityPersister] Hydrating entity: [com.comarch.wfm.daohibernate.DayVO#6]
2006-07-19 15:06:27,000 DEBUG [org.hibernate.type.DateType] returning '06 stycze˝ 2001' as column: DayDate0_0_
2006-07-19 15:06:27,000 DEBUG [org.hibernate.type.TimestampType] returning '2006-07-10 09:07:18' as column: atime0_0_
2006-07-19 15:06:27,000 DEBUG [org.hibernate.type.LongType] returning '1' as column: ctx4_0_0_
2006-07-19 15:06:27,000 DEBUG [org.hibernate.type.LongType] returning null as column: mtx5_0_0_
2006-07-19 15:06:27,000 DEBUG [org.hibernate.loader.Loader] done processing result set (1 rows)
2006-07-19 15:06:27,000 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2006-07-19 15:06:27,000 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-07-19 15:06:27,000 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing statement
2006-07-19 15:06:27,000 DEBUG [org.hibernate.loader.Loader] total objects hydrated: 1
2006-07-19 15:06:27,000 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.comarch.wfm.daohibernate.DayVO#6]
2006-07-19 15:06:27,015 DEBUG [org.hibernate.engine.CollectionLoadContext] creating collection wrapper:[com.comarch.wfm.daohibernate.DayVO.workCycleDays#6]
2006-07-19 15:06:27,031 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.comarch.wfm.daohibernate.DayVO#6]
2006-07-19 15:06:27,031 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2006-07-19 15:06:27,031 DEBUG [org.hibernate.transaction.JDBCTransaction] commit
2006-07-19 15:06:27,031 DEBUG [org.hibernate.impl.SessionImpl] automatically flushing session
2006-07-19 15:06:27,031 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] flushing session
2006-07-19 15:06:27,031 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] processing flush-time cascades
2006-07-19 15:06:27,031 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] dirty checking collections
2006-07-19 15:06:27,031 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushing entities and processing referenced collections
2006-07-19 15:06:27,031 DEBUG [org.hibernate.engine.Collections] Collection found: [com.comarch.wfm.daohibernate.DayVO.workCycleDays#6], was: [com.comarch.wfm.daohibernate.DayVO.workCycleDays#6] (uninitialized)
2006-07-19 15:06:27,031 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Processing unreferenced collections
2006-07-19 15:06:27,031 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Scheduling collection removes/(re)creates/updates
2006-07-19 15:06:27,031 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects
2006-07-19 15:06:27,031 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 0 (re)creations, 0 updates, 0 removals to 1 collections
2006-07-19 15:06:27,031 DEBUG [org.hibernate.pretty.Printer] listing entities:
2006-07-19 15:06:27,046 DEBUG [org.hibernate.pretty.Printer] com.comarch.wfm.daohibernate.DayVO{mtx_id=null, ctx_id=1, workCycleDays=<uninitialized>, dayDate=06 stycze˝ 2001, atime=2006-07-10 09:07:18, id=6}
2006-07-19 15:06:27,046 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] executing flush
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.ConnectionManager] registering flush begin
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.ConnectionManager] registering flush end
2006-07-19 15:06:27,046 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] post flush
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.JDBCContext] before transaction completion
2006-07-19 15:06:27,046 DEBUG [org.hibernate.impl.SessionImpl] before transaction completion
2006-07-19 15:06:27,046 DEBUG [org.hibernate.transaction.JDBCTransaction] committed JDBC Connection
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.JDBCContext] after transaction completion
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively releasing JDBC connection
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.connection.DriverManagerConnectionProvider] returning connection to pool, pool size: 1
2006-07-19 15:06:27,046 DEBUG [org.hibernate.impl.SessionImpl] after transaction completion
2006-07-19 15:06:27,046 DEBUG [org.hibernate.impl.SessionImpl] closing session
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.ConnectionManager] connection already null in cleanup : no action
2006-07-19 15:06:27,046 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 4723975729340416
2006-07-19 15:06:27,046 DEBUG [org.hibernate.transaction.JDBCTransaction] begin
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection
2006-07-19 15:06:27,046 DEBUG [org.hibernate.connection.DriverManagerConnectionProvider] total checked-out connections: 0
2006-07-19 15:06:27,046 DEBUG [org.hibernate.connection.DriverManagerConnectionProvider] using pooled JDBC connection, pool size: 0
2006-07-19 15:06:27,046 DEBUG [org.hibernate.transaction.JDBCTransaction] current autocommit status: false
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.JDBCContext] after transaction begin
2006-07-19 15:06:27,046 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] attempting to resolve: [com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] object not resolved in any cache: [com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.persister.entity.AbstractEntityPersister] Fetching entity: [com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.loader.Loader] loading entity: [com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-07-19 15:06:27,046 DEBUG [org.hibernate.SQL] select dayvo0_.id as id0_0_, dayvo0_.DayDate as DayDate0_0_, dayvo0_.atime as atime0_0_, dayvo0_.ctx_id as ctx4_0_0_, dayvo0_.mtx_id as mtx5_0_0_ from tWFMCalDays dayvo0_ where dayvo0_.id=?
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2006-07-19 15:06:27,046 DEBUG [org.hibernate.type.LongType] binding '30' to parameter: 1
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2006-07-19 15:06:27,046 DEBUG [org.hibernate.loader.Loader] processing result set
2006-07-19 15:06:27,046 DEBUG [org.hibernate.loader.Loader] result set row: 0
2006-07-19 15:06:27,046 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.persister.entity.AbstractEntityPersister] Hydrating entity: [com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.type.DateType] returning '30 stycze˝ 2001' as column: DayDate0_0_
2006-07-19 15:06:27,046 DEBUG [org.hibernate.type.TimestampType] returning '2006-07-10 09:07:19' as column: atime0_0_
2006-07-19 15:06:27,046 DEBUG [org.hibernate.type.LongType] returning '1' as column: ctx4_0_0_
2006-07-19 15:06:27,046 DEBUG [org.hibernate.type.LongType] returning null as column: mtx5_0_0_
2006-07-19 15:06:27,046 DEBUG [org.hibernate.loader.Loader] done processing result set (1 rows)
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-07-19 15:06:27,046 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing statement
2006-07-19 15:06:27,046 DEBUG [org.hibernate.loader.Loader] total objects hydrated: 1
2006-07-19 15:06:27,046 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.engine.CollectionLoadContext] creating collection wrapper:[com.comarch.wfm.daohibernate.DayVO.workCycleDays#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [com.comarch.wfm.daohibernate.DayVO#30]
2006-07-19 15:06:27,046 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2006-07-19 15:06:27,046 DEBUG [org.hibernate.loader.Loader] done entity load
2006-07-19 15:06:27,078 DEBUG [org.hibernate.engine.query.QueryPlanCache] unable to locate HQL query plan in cache; generating (update GroupHVO SET dayDeleted = :today, atime = :sysdat, mtx_id = :mtx where path.dayFrom.id > :todayId and group.id = :groupId and dayDeleted is null)
2006-07-19 15:06:27,171 DEBUG [org.hibernate.hql.ast.QueryTranslatorImpl] parse() - HQL: update com.comarch.wfm.daohibernate.GroupHVO SET dayDeleted = :today, atime = :sysdat, mtx_id = :mtx where path.dayFrom.id > :todayId and group.id = :groupId and dayDeleted is null
2006-07-19 15:06:27,187 DEBUG [org.hibernate.hql.ast.HqlParser] weakKeywords() : new LT(1) token - ["group",<119> previously: <24>,line=1,col=146,possibleID=true]
2006-07-19 15:06:27,203 DEBUG [org.hibernate.hql.ast.AST] --- HQL AST ---
\-[UPDATE] 'update'
+-[FROM] 'FROM'
| \-[RANGE] 'RANGE'
| \-[DOT] '.'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[DOT] '.'
| | | | +-[IDENT] 'com'
| | | | \-[IDENT] 'comarch'
| | | \-[IDENT] 'wfm'
| | \-[IDENT] 'daohibernate'
| \-[IDENT] 'GroupHVO'
+-[SET] 'SET'
| +-[EQ] '='
| | +-[IDENT] 'dayDeleted'
| | \-[COLON] ':'
| | \-[IDENT] 'today'
| +-[EQ] '='
| | +-[IDENT] 'atime'
| | \-[COLON] ':'
| | \-[IDENT] 'sysdat'
| \-[EQ] '='
| +-[IDENT] 'mtx_id'
| \-[COLON] ':'
| \-[IDENT] 'mtx'
\-[WHERE] 'where'
\-[AND] 'and'
+-[AND] 'and'
| +-[GT] '>'
| | +-[DOT] '.'
| | | +-[DOT] '.'
| | | | +-[IDENT] 'path'
| | | | \-[IDENT] 'dayFrom'
| | | \-[IDENT] 'id'
| | \-[COLON] ':'
| | \-[IDENT] 'todayId'
| \-[EQ] '='
| +-[DOT] '.'
| | +-[IDENT] 'group'
| | \-[IDENT] 'id'
| \-[COLON] ':'
| \-[IDENT] 'groupId'
\-[IS_NULL] 'is null'
\-[IDENT] 'dayDeleted'

2006-07-19 15:06:27,203 DEBUG [org.hibernate.hql.ast.ErrorCounter] throwQueryException() : no errors
2006-07-19 15:06:27,281 DEBUG [org.hibernate.hql.antlr.HqlSqlBaseWalker] update << begin [level=1, statement=update]
2006-07-19 15:06:27,312 DEBUG [org.hibernate.hql.ast.tree.FromElement] FromClause{level=1} : com.comarch.wfm.daohibernate.GroupHVO (no alias) -> grouphvo0_
2006-07-19 15:06:27,312 DEBUG [org.hibernate.hql.ast.HqlSqlWalker] attempting to resolve property [dayDeleted] as a non-qualified ref
2006-07-19 15:06:27,312 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias} -> {synthetic-alias}
2006-07-19 15:06:27,312 DEBUG [org.hibernate.hql.ast.tree.DotNode] getDataType() : dayDeleted -> org.hibernate.type.ManyToOneType(com.comarch.wfm.daohibernate.DayVO)
2006-07-19 15:06:27,312 DEBUG [org.hibernate.hql.ast.tree.DotNode] dereferenceShortcut() : property dayDeleted in com.comarch.wfm.daohibernate.GroupHVO does not require a join.
2006-07-19 15:06:27,312 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias}.dayDeleted -> DayDeleted_Id
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.HqlSqlWalker] attempting to resolve property [atime] as a non-qualified ref
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias} -> {synthetic-alias}
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] getDataType() : atime -> org.hibernate.type.TimestampType@1e0512a
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias}.atime -> atime
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.HqlSqlWalker] attempting to resolve property [mtx_id] as a non-qualified ref
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias} -> {synthetic-alias}
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] getDataType() : mtx_id -> org.hibernate.type.LongType@1f0aecc
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias}.mtx_id -> mtx_id
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.HqlSqlWalker] attempting to resolve property [path] as a non-qualified ref
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias} -> {synthetic-alias}
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] getDataType() : path -> org.hibernate.type.ManyToOneType(com.comarch.wfm.daohibernate.GroupHPVO)
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] dereferenceEntityJoin() : generating join for path in com.comarch.wfm.daohibernate.GroupHVO {no alias} parent = [ ( . ( {non-qualified-property-ref} {synthetic-alias} path ) dayFrom ) ]
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromElement] FromClause{level=1} : com.comarch.wfm.daohibernate.GroupHPVO (no alias) -> grouphpvo1_
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromClause] addJoinByPathMap() : {synthetic-alias}.path -> twfmGrpGroupHierarchyPaths grouphpvo1_
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias}.path -> path_Id
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] getDataType() : dayFrom -> org.hibernate.type.ManyToOneType(com.comarch.wfm.daohibernate.DayVO)
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] dereferenceShortcut() : property id in com.comarch.wfm.daohibernate.GroupHPVO does not require a join.
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] Unresolved property path is now 'dayFrom.id'
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias}.path.dayFrom -> DayFrom_Id
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] getDataType() : dayFrom.id -> org.hibernate.type.LongType@1f0aecc
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias}.path.dayFrom.id -> DayFrom_Id
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.HqlSqlWalker] attempting to resolve property [group] as a non-qualified ref
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias} -> {synthetic-alias}
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] getDataType() : group -> org.hibernate.type.ManyToOneType(com.comarch.wfm.daohibernate.GroupVO)
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] dereferenceShortcut() : property id in com.comarch.wfm.daohibernate.GroupHVO does not require a join.
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] Unresolved property path is now 'group.id'
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias}.group -> group_Id
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] getDataType() : group.id -> org.hibernate.type.LongType@1f0aecc
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias}.group.id -> group_Id
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.HqlSqlWalker] attempting to resolve property [dayDeleted] as a non-qualified ref
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias} -> {synthetic-alias}
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] getDataType() : dayDeleted -> org.hibernate.type.ManyToOneType(com.comarch.wfm.daohibernate.DayVO)
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.DotNode] dereferenceShortcut() : property dayDeleted in com.comarch.wfm.daohibernate.GroupHVO does not require a join.
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.ast.tree.FromReferenceNode] Resolved : {synthetic-alias}.dayDeleted -> DayDeleted_Id
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.antlr.HqlSqlBaseWalker] update : finishing up [level=1, statement=update]
2006-07-19 15:06:27,328 DEBUG [org.hibernate.hql.antlr.HqlSqlBaseWalker] update >> end [level=1, statement=update]
2006-07-19 15:06:27,343 DEBUG [org.hibernate.hql.ast.AST] --- SQL AST ---
\-[UPDATE] UpdateStatement: 'update' querySpaces (tWFMGrpGroupsHierarchy,twfmGrpGroupHierarchyPaths)
+-[FROM] FromClause: 'FROM' FromClause{level=1, fromElementCounter=2, fromElements=2, fromElementByClassAlias=[], fromElementByTableAlias=[grouphvo0_, grouphpvo1_], fromElementsByPath=[{synthetic-alias}.path], collectionJoinFromElementsByPath=[], impliedElements=[]}
| +-[FROM_FRAGMENT] FromElement: 'tWFMGrpGroupsHierarchy' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=tWFMGrpGroupsHierarchy,tableAlias=grouphvo0_,origin=null,colums={,className=com.comarch.wfm.daohibernate.GroupHVO}}
| \-[JOIN_FRAGMENT] ImpliedFromElement: 'twfmGrpGroupHierarchyPaths grouphpvo1_' ImpliedFromElement{implied,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=twfmGrpGroupHierarchyPaths,tableAlias=grouphpvo1_,origin=tWFMGrpGroupsHierarchy,colums={path_Id ,className=com.comarch.wfm.daohibernate.GroupHPVO}}
+-[SET] SqlNode: 'SET'
| +-[EQ] BinaryLogicOperatorNode: '='
| | +-[DOT] DotNode: 'DayDeleted_Id' {propertyName=dayDeleted,dereferenceType=ROOT_LEVEL,propertyPath=dayDeleted,path={synthetic-alias}.dayDeleted,tableAlias=grouphvo0_,className=com.comarch.wfm.daohibernate.GroupHVO,classAlias=null}
| | | +-[IDENT] IdentNode: '{synthetic-alias}' {originalText={synthetic-alias}}
| | | \-[IDENT] IdentNode: 'dayDeleted' {originalText=dayDeleted}
| | \-[NAMED_PARAM] ParameterNode: '?' {name=today, expectedType=null}
| +-[EQ] BinaryLogicOperatorNode: '='
| | +-[DOT] DotNode: 'atime' {propertyName=atime,dereferenceType=4,propertyPath=atime,path={synthetic-alias}.atime,tableAlias=grouphvo0_,className=com.comarch.wfm.daohibernate.GroupHVO,classAlias=null}
| | | +-[IDENT] IdentNode: '{synthetic-alias}' {originalText={synthetic-alias}}
| | | \-[IDENT] IdentNode: 'atime' {originalText=atime}
| | \-[NAMED_PARAM] ParameterNode: '?' {name=sysdat, expectedType=null}
| \-[EQ] BinaryLogicOperatorNode: '='
| +-[DOT] DotNode: 'mtx_id' {propertyName=mtx_id,dereferenceType=4,propertyPath=mtx_id,path={synthetic-alias}.mtx_id,tableAlias=grouphvo0_,className=com.comarch.wfm.daohibernate.GroupHVO,classAlias=null}
| | +-[IDENT] IdentNode: '{synthetic-alias}' {originalText={synthetic-alias}}
| | \-[IDENT] IdentNode: 'mtx_id' {originalText=mtx_id}
| \-[NAMED_PARAM] ParameterNode: '?' {name=mtx, expectedType=null}
\-[WHERE] SqlNode: 'where'
\-[AND] SqlNode: 'and'
+-[AND] SqlNode: 'and'
| +-[GT] BinaryLogicOperatorNode: '>'
| | +-[DOT] DotNode: 'DayFrom_Id' {propertyName=id,dereferenceType=4,propertyPath=dayFrom.id,path={synthetic-alias}.path.dayFrom.id,tableAlias=grouphpvo1_,className=com.comarch.wfm.daohibernate.GroupHPVO,classAlias=null}
| | | +-[DOT] DotNode: 'DayFrom_Id' {propertyName=id,dereferenceType=ROOT_LEVEL,propertyPath=dayFrom.id,path={synthetic-alias}.path.dayFrom,tableAlias=grouphpvo1_,className=com.comarch.wfm.daohibernate.GroupHPVO,classAlias=null}
| | | | +-[DOT] DotNode: 'path_Id' {propertyName=path,dereferenceType=1,propertyPath=path,path={synthetic-alias}.path,tableAlias=grouphpvo1_,className=com.comarch.wfm.daohibernate.GroupHPVO,classAlias=null}
| | | | | +-[IDENT] IdentNode: '{synthetic-alias}' {originalText={synthetic-alias}}
| | | | | \-[IDENT] IdentNode: 'path' {originalText=path}
| | | | \-[IDENT] IdentNode: 'dayFrom' {originalText=dayFrom}
| | | \-[IDENT] IdentNode: 'id' {originalText=id}
| | \-[NAMED_PARAM] ParameterNode: '?' {name=todayId, expectedType=org.hibernate.type.LongType@1f0aecc}
| \-[EQ] BinaryLogicOperatorNode: '='
| +-[DOT] DotNode: 'group_Id' {propertyName=id,dereferenceType=4,propertyPath=group.id,path={synthetic-alias}.group.id,tableAlias=grouphvo0_,className=com.comarch.wfm.daohibernate.GroupHVO,classAlias=null}
| | +-[DOT] DotNode: 'group_Id' {propertyName=id,dereferenceType=ROOT_LEVEL,propertyPath=group.id,path={synthetic-alias}.group,tableAlias=grouphvo0_,className=com.comarch.wfm.daohibernate.GroupHVO,classAlias=null}
| | | +-[IDENT] IdentNode: '{synthetic-alias}' {originalText={synthetic-alias}}
| | | \-[IDENT] IdentNode: 'group' {originalText=group}
| | \-[IDENT] IdentNode: 'id' {originalText=id}
| \-[NAMED_PARAM] ParameterNode: '?' {name=groupId, expectedType=org.hibernate.type.LongType@1f0aecc}
\-[IS_NULL] UnaryLogicOperatorNode: 'is null'
\-[DOT] DotNode: 'DayDeleted_Id' {propertyName=dayDeleted,dereferenceType=ROOT_LEVEL,propertyPath=dayDeleted,path={synthetic-alias}.dayDeleted,tableAlias=grouphvo0_,className=com.comarch.wfm.daohibernate.GroupHVO,classAlias=null}
+-[IDENT] IdentNode: '{synthetic-alias}' {originalText={synthetic-alias}}
\-[IDENT] IdentNode: 'dayDeleted' {originalText=dayDeleted}

2006-07-19 15:06:27,343 DEBUG [org.hibernate.hql.ast.ErrorCounter] throwQueryException() : no errors
2006-07-19 15:06:27,375 DEBUG [org.hibernate.hql.ast.ErrorCounter] throwQueryException() : no errors
2006-07-19 15:06:27,375 DEBUG [org.hibernate.engine.query.HQLQueryPlan] HQL param location recognition took 0 mills (update GroupHVO SET dayDeleted = :today, atime = :sysdat, mtx_id = :mtx where path.dayFrom.id > :todayId and group.id = :groupId and dayDeleted is null)
2006-07-19 15:06:27,390 DEBUG [org.hibernate.engine.query.QueryPlanCache] located HQL query plan in cache (update GroupHVO SET dayDeleted = :today, atime = :sysdat, mtx_id = :mtx where path.dayFrom.id > :todayId and group.id = :groupId and dayDeleted is null)
2006-07-19 15:06:27,390 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] flushing session
2006-07-19 15:06:27,390 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] processing flush-time cascades
2006-07-19 15:06:27,390 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] dirty checking collections
2006-07-19 15:06:27,390 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushing entities and processing referenced collections
2006-07-19 15:06:27,390 DEBUG [org.hibernate.engine.Collections] Collection found: [com.comarch.wfm.daohibernate.DayVO.workCycleDays#30], was: [com.comarch.wfm.daohibernate.DayVO.workCycleDays#30] (uninitialized)
2006-07-19 15:06:27,390 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Processing unreferenced collections
2006-07-19 15:06:27,390 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Scheduling collection removes/(re)creates/updates
2006-07-19 15:06:27,390 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 0 insertions, 0 updates, 0 deletions to 1 objects
2006-07-19 15:06:27,390 DEBUG [org.hibernate.event.def.AbstractFlushingEventListener] Flushed: 0 (re)creations, 0 updates, 0 removals to 1 collections
2006-07-19 15:06:27,390 DEBUG [org.hibernate.pretty.Printer] listing entities:
2006-07-19 15:06:27,390 DEBUG [org.hibernate.pretty.Printer] com.comarch.wfm.daohibernate.DayVO{mtx_id=null, ctx_id=1, workCycleDays=<uninitialized>, dayDate=30 stycze˝ 2001, atime=2006-07-10 09:07:19, id=30}
2006-07-19 15:06:27,390 DEBUG [org.hibernate.event.def.DefaultAutoFlushEventListener] Dont need to execute flush
2006-07-19 15:06:27,390 DEBUG [org.hibernate.engine.query.HQLQueryPlan] executeUpdate: update GroupHVO SET dayDeleted = :today, atime = :sysdat, mtx_id = :mtx where path.dayFrom.id > :todayId and group.id = :groupId and dayDeleted is null
2006-07-19 15:06:27,390 DEBUG [org.hibernate.engine.QueryParameters] named parameters: {groupId=18, today=com.comarch.wfm.daohibernate.DayVO#30, sysdat=2006-07-19 00:00:00, mtx=1, todayId=30}
2006-07-19 15:06:27,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006-07-19 15:06:27,406 DEBUG [org.hibernate.SQL] update tWFMGrpGroupsHierarchy, set DayDeleted_Id=?, atime=?, mtx_id=? where DayFrom_Id>? and group_Id=? and (DayDeleted_Id is null)
2006-07-19 15:06:27,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2006-07-19 15:06:27,406 DEBUG [org.hibernate.type.LongType] binding '30' to parameter: 1
2006-07-19 15:06:27,406 DEBUG [org.hibernate.type.TimestampType] binding '2006-07-19 00:00:00' to parameter: 2
2006-07-19 15:06:27,406 DEBUG [org.hibernate.type.LongType] binding '1' to parameter: 3
2006-07-19 15:06:27,406 DEBUG [org.hibernate.type.LongType] binding '30' to parameter: 4
2006-07-19 15:06:27,406 DEBUG [org.hibernate.type.LongType] binding '18' to parameter: 5
2006-07-19 15:06:27,421 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006-07-19 15:06:27,421 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing statement
2006-07-19 15:06:27,421 DEBUG [org.hibernate.util.JDBCExceptionReporter] could not execute update query [update tWFMGrpGroupsHierarchy, set DayDeleted_Id=?, atime=?, mtx_id=? where DayFrom_Id>? and group_Id=? and (DayDeleted_Id is null)]
java.sql.SQLException: ORA-00971: brak s│owa kluczowego SET

at oracle.jdbc.dbaccess.DBError.throwSqlException(DBError.java:134)
at oracle.jdbc.oci8.OCIDBAccess.check_error(OCIDBAccess.java:2355)
at oracle.jdbc.oci8.OCIDBAccess.executeFetch(OCIDBAccess.java:1760)
at oracle.jdbc.oci8.OCIDBAccess.parseExecuteFetch(OCIDBAccess.java:1921)
at oracle.jdbc.driver.OracleStatement.executeNonQuery(OracleStatement.java:2152)
at oracle.jdbc.driver.OracleStatement.doExecuteOther(OracleStatement.java:2035)
at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:2876)
at oracle.jdbc.driver.OraclePreparedStatement.executeUpdate(OraclePreparedStatement.java:609)
at org.hibernate.hql.ast.exec.BasicExecutor.execute(BasicExecutor.java:75)
at org.hibernate.hql.ast.QueryTranslatorImpl.executeUpdate(QueryTranslatorImpl.java:334)
at org.hibernate.engine.query.HQLQueryPlan.performExecuteUpdate(HQLQueryPlan.java:209)
at org.hibernate.impl.SessionImpl.executeUpdate(SessionImpl.java:1126)
at org.hibernate.impl.QueryImpl.executeUpdate(QueryImpl.java:94)
at dupa.A001.main(A001.java:102)
2006-07-19 15:06:27,421 WARN [org.hibernate.util.JDBCExceptionReporter] SQL Error: 971, SQLState: 42000
2006-07-19 15:06:27,421 ERROR [org.hibernate.util.JDBCExceptionReporter] ORA-00971: brak s│owa kluczowego SET

Can you confirm the setting of your:

hibernate.dialect=org.hibernate.dialect.???????????????

Normally this is indicated during SessionFactory creation. Is the correct dialect selected for Oracle ?

Yes - dialect is ok : org.hibernate.dialect.Oracle9Dialect i try pure Oracle too with no efect..

Acure

I found that the problem occurs only when in query I use nested properties.

For example:
" path.dayFrom.id > :todayId and " +
" group.id = :groupId and " +

When nested properties are not used is seems that bulk updated works correctly.

Sorry for reopening this old post...
Was this problem fixed ?

I am also facing same problem. I am trying to retrieve data from oracle 10g,
program works fine for other calls except this one.. error highlighted in below stack..

in mapped class variable has Date datatype and database also has Date datatype for the mapped field.

Thanks in advance.

Error Stack:


17:42:27,782 INFO Version:15 - Hibernate Annotations 3.4.0.GA
17:42:27,798 INFO Environment:560 - Hibernate 3.3.2.GA
17:42:27,800 INFO Environment:593 - hibernate.properties not found
17:42:27,803 INFO Environment:771 - Bytecode provider name : javassist
17:42:27,807 INFO Environment:652 - using JDK 1.4 java.sql.Timestamp handling
17:42:27,865 INFO Version:14 - Hibernate Commons Annotations 3.1.0.GA
17:42:27,867 INFO Configuration:1474 - configuring from resource: /hibernate.cfg.xml
17:42:27,868 INFO Configuration:1451 - Configuration resource: /hibernate.cfg.xml
17:42:27,911 DEBUG DTDEntityResolver:64 - trying to resolve system-id [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd]
17:42:27,911 DEBUG DTDEntityResolver:66 - recognized hibernate namespace; attempting to resolve on classpath under org/hibernate/
17:42:27,914 DEBUG DTDEntityResolver:76 - located [http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd] in classpath
17:42:27,933 DEBUG Configuration:1435 - hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver
17:42:27,934 DEBUG Configuration:1435 - hibernate.connection.url=jdbc:oracle:thin:@localhost:1521:XE
17:42:27,934 DEBUG Configuration:1435 - hibernate.connection.username=amoghsa
17:42:27,935 DEBUG Configuration:1435 - hibernate.connection.password=mywebsite
17:42:27,935 DEBUG Configuration:1435 - hibernate.dialect=org.hibernate.dialect.Oracle10gDialect
17:42:27,936 DEBUG Configuration:1435 - show_sql=true
17:42:27,937 DEBUG Configuration:1435 - hbm2ddl.auto=update
17:42:27,937 DEBUG AnnotationConfiguration:644 - null <- org.dom4j.tree.DefaultAttribute@4d865b28 [Attribute: name class value "com.amoghsweb.footiecal.ClubDetails"]
17:42:27,948 DEBUG AnnotationConfiguration:644 - null <- org.dom4j.tree.DefaultAttribute@20b9b538 [Attribute: name class value "com.amoghsweb.footiecal.GameData"]
17:42:27,949 INFO Configuration:1589 - Configured SessionFactory: null
17:42:27,950 DEBUG Configuration:1590 - properties: {hibernate.connection.password=mywebsite, java.runtime.name=Java(TM) SE Runtime Environment, sun.boot.library.path=C:\Program Files\Java\jre6\bin, java.vm.version=16.3-b01, hibernate.connection.username=amoghsa, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) 64-Bit Server VM, file.encoding.pkg=sun.io, user.country=IN, sun.java.launcher=SUN_STANDARD, sun.os.patch.level=, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\Amogh_WS\FootieCal\FootieCal, java.runtime.version=1.6.0_20-b02, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, hbm2ddl.auto=update, java.endorsed.dirs=C:\Program Files\Java\jre6\lib\endorsed, os.arch=amd64, java.io.tmpdir=C:\Users\Dell\AppData\Local\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows 7, sun.jnu.encoding=Cp1252, java.library.path=C:\Program Files\Java\jre6\bin;.;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\oraclexe\app\oracle\product\10.2.0\server\bin;C:\Program Files (x86)\PC Connectivity Solution\;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files\Dell\DW WLAN Card;c:\Program Files\WIDCOMM\Bluetooth Software\;c:\Program Files\WIDCOMM\Bluetooth Software\syswow64;c:\Program Files (x86)\ATI Technologies\ATI.ACE\Core-Static;c:\Program Files (x86)\Common Files\Roxio Shared\DLLShared\;C:\Program Files (x86)\QuickTime\QTSystem\, java.specification.name=Java Platform API Specification, java.class.version=50.0, sun.management.compiler=HotSpot 64-Bit Server Compiler, os.version=6.1, user.home=C:\Users\Dell, user.timezone=Asia/Calcutta, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.6, hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver, show_sql=true, user.name=Dell, java.class.path=C:\Amogh_WS\FootieCal\FootieCal\build\classes;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\antlr-2.7.6.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\commons-collections-3.1.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\dom4j-1.6.1.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\ejb3-persistence.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\hibernate-annotations.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\hibernate-commons-annotations.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\hibernate-entitymanager.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\hibernate3.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\javassist-3.9.0.GA.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\jta-1.1.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\jxl.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\log4j-1.2.15.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\ojdbc14.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\slf4j-api-1.5.8.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\slf4j-log4j12-1.5.8.jar, hibernate.bytecode.use_reflection_optimizer=false, hibernate.show_sql=true, java.vm.specification.version=1.0, java.home=C:\Program Files\Java\jre6, sun.arch.data.model=64, hibernate.dialect=org.hibernate.dialect.Oracle10gDialect, hibernate.connection.url=jdbc:oracle:thin:@localhost:1521:XE, user.language=en, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, java.version=1.6.0_20, java.ext.dirs=C:\Program Files\Java\jre6\lib\ext;C:\Windows\Sun\Java\lib\ext, sun.boot.class.path=C:\Program Files\Java\jre6\lib\resources.jar;C:\Program Files\Java\jre6\lib\rt.jar;C:\Program Files\Java\jre6\lib\sunrsasign.jar;C:\Program Files\Java\jre6\lib\jsse.jar;C:\Program Files\Java\jre6\lib\jce.jar;C:\Program Files\Java\jre6\lib\charsets.jar;C:\Program Files\Java\jre6\classes, java.vendor=Sun Microsystems Inc., file.separator=\, hibernate.hbm2ddl.auto=update, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, sun.desktop=windows, sun.cpu.isalist=amd64}
17:42:27,951 DEBUG AnnotationConfiguration:806 - Validator not present in classpath, ignoring event listener registration
17:42:27,953 DEBUG HibernateSearchEventListenerRegister:209 - Search not present in classpath, ignoring event listener registration.
17:42:27,954 INFO HibernateSearchEventListenerRegister:53 - Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
17:42:27,954 DEBUG Configuration:1332 - Preparing to build session factory with filters : {}
17:42:27,954 DEBUG AnnotationConfiguration:258 - Execute first pass mapping processing
17:42:28,000 DEBUG AnnotationConfiguration:529 - Process hbm files
17:42:28,000 DEBUG AnnotationConfiguration:537 - Process annotated classes
17:42:28,003 INFO AnnotationBinder:419 - Binding entity from annotated class: com.amoghsweb.footiecal.GameData
17:42:28,017 DEBUG Ejb3Column:161 - Binding column DTYPE. Unique false. Nullable false.
17:42:28,027 DEBUG EntityBinder:295 - Import with entity name GameData
17:42:28,029 INFO EntityBinder:422 - Bind entity com.amoghsweb.footiecal.GameData on table GAME_DATA
17:42:28,033 DEBUG AnnotationBinder:1022 - Processing com.amoghsweb.footiecal.GameData property annotation
17:42:28,044 DEBUG AnnotationBinder:1022 - Processing com.amoghsweb.footiecal.GameData field annotation
17:42:28,056 DEBUG AnnotationBinder:1133 - Processing annotations of com.amoghsweb.footiecal.GameData.gameId
17:42:28,058 DEBUG Ejb3Column:161 - Binding column GAME_ID. Unique false. Nullable true.
17:42:28,060 DEBUG AnnotationBinder:1257 - gameId is an id
17:42:28,064 DEBUG SimpleValueBinder:220 - building SimpleValue for gameId
17:42:28,066 DEBUG PropertyBinder:131 - Building property gameId
17:42:28,069 DEBUG AnnotationBinder:1293 - Bind @Id on gameId
17:42:28,070 DEBUG AnnotationBinder:1133 - Processing annotations of com.amoghsweb.footiecal.GameData.gameDate
17:42:28,070 DEBUG Ejb3Column:161 - Binding column GAME_DATE. Unique false. Nullable true.
17:42:28,072 DEBUG PropertyBinder:110 - binding property gameDate with lazy=false
17:42:28,073 DEBUG SimpleValueBinder:220 - building SimpleValue for gameDate
17:42:28,073 DEBUG PropertyBinder:131 - Building property gameDate
17:42:28,073 DEBUG AnnotationBinder:1133 - Processing annotations of com.amoghsweb.footiecal.GameData.place
17:42:28,074 DEBUG Ejb3Column:161 - Binding column PLACE. Unique false. Nullable true.
17:42:28,074 DEBUG PropertyBinder:110 - binding property place with lazy=false
17:42:28,075 DEBUG SimpleValueBinder:220 - building SimpleValue for place
17:42:28,075 DEBUG PropertyBinder:131 - Building property place
17:42:28,075 DEBUG AnnotationBinder:1133 - Processing annotations of com.amoghsweb.footiecal.GameData.result
17:42:28,076 DEBUG Ejb3Column:161 - Binding column RESULT. Unique false. Nullable true.
17:42:28,076 DEBUG PropertyBinder:110 - binding property result with lazy=false
17:42:28,077 DEBUG SimpleValueBinder:220 - building SimpleValue for result
17:42:28,077 DEBUG PropertyBinder:131 - Building property result
17:42:28,078 DEBUG AnnotationBinder:1133 - Processing annotations of com.amoghsweb.footiecal.GameData.team1
17:42:28,078 DEBUG Ejb3Column:161 - Binding column TEAM1. Unique false. Nullable true.
17:42:28,078 DEBUG PropertyBinder:110 - binding property team1 with lazy=false
17:42:28,079 DEBUG SimpleValueBinder:220 - building SimpleValue for team1
17:42:28,079 DEBUG PropertyBinder:131 - Building property team1
17:42:28,080 DEBUG AnnotationBinder:1133 - Processing annotations of com.amoghsweb.footiecal.GameData.team2
17:42:28,080 DEBUG Ejb3Column:161 - Binding column TEAM2. Unique false. Nullable true.
17:42:28,081 DEBUG PropertyBinder:110 - binding property team2 with lazy=false
17:42:28,081 DEBUG SimpleValueBinder:220 - building SimpleValue for team2
17:42:28,081 DEBUG PropertyBinder:131 - Building property team2
17:42:28,083 DEBUG AnnotationConfiguration:401 - processing fk mappings (*ToOne and JoinedSubclass)
17:42:28,085 DEBUG Configuration:1167 - processing extends queue
17:42:28,085 DEBUG Configuration:1171 - processing collection mappings
17:42:28,086 DEBUG Configuration:1182 - processing native query and ResultSetMapping mappings
17:42:28,086 DEBUG Configuration:1190 - processing association property references
17:42:28,086 DEBUG Configuration:1212 - processing foreign key constraints
17:42:28,087 INFO AnnotationConfiguration:369 - Hibernate Validator not found: ignoring
17:42:28,134 INFO DriverManagerConnectionProvider:64 - Using Hibernate built-in connection pool (not for production use!)
17:42:28,134 INFO DriverManagerConnectionProvider:65 - Hibernate connection pool size: 20
17:42:28,135 INFO DriverManagerConnectionProvider:68 - autocommit mode: false
17:42:28,140 INFO DriverManagerConnectionProvider:103 - using driver: oracle.jdbc.driver.OracleDriver at URL: jdbc:oracle:thin:@localhost:1521:XE
17:42:28,141 INFO DriverManagerConnectionProvider:106 - connection properties: {user=amoghsa, password=mywebsite}
17:42:28,141 DEBUG DriverManagerConnectionProvider:132 - opening new JDBC connection
17:42:28,386 DEBUG DriverManagerConnectionProvider:138 - created connection to: jdbc:oracle:thin:@localhost:1521:XE, Isolation Level: 2
17:42:28,388 INFO SettingsFactory:114 - RDBMS: Oracle, version: Oracle Database 10g Express Edition Release 10.2.0.1.0 - Production
17:42:28,389 INFO SettingsFactory:115 - JDBC driver: Oracle JDBC driver, version: 10.2.0.1.0XE
17:42:28,409 INFO Dialect:175 - Using dialect: org.hibernate.dialect.Oracle10gDialect
17:42:28,414 INFO TransactionFactoryFactory:59 - Using default transaction strategy (direct JDBC transactions)
17:42:28,416 INFO TransactionManagerLookupFactory:80 - No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
17:42:28,417 INFO SettingsFactory:161 - Automatic flush during beforeCompletion(): disabled
17:42:28,417 INFO SettingsFactory:165 - Automatic session close at end of transaction: disabled
17:42:28,417 INFO SettingsFactory:172 - JDBC batch size: 15
17:42:28,417 INFO SettingsFactory:175 - JDBC batch updates for versioned data: disabled
17:42:28,418 INFO SettingsFactory:180 - Scrollable result sets: enabled
17:42:28,418 DEBUG SettingsFactory:184 - Wrap result sets: disabled
17:42:28,418 INFO SettingsFactory:188 - JDBC3 getGeneratedKeys(): disabled
17:42:28,419 INFO SettingsFactory:196 - Connection release mode: auto
17:42:28,420 INFO SettingsFactory:223 - Default batch fetch size: 1
17:42:28,420 INFO SettingsFactory:227 - Generate SQL with comments: disabled
17:42:28,420 INFO SettingsFactory:231 - Order SQL updates by primary key: disabled
17:42:28,420 INFO SettingsFactory:235 - Order SQL inserts for batching: disabled
17:42:28,421 INFO SettingsFactory:397 - Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
17:42:28,423 INFO ASTQueryTranslatorFactory:47 - Using ASTQueryTranslatorFactory
17:42:28,423 INFO SettingsFactory:243 - Query language substitutions: {}
17:42:28,423 INFO SettingsFactory:248 - JPA-QL strict compliance: disabled
17:42:28,423 INFO SettingsFactory:253 - Second-level cache: enabled
17:42:28,424 INFO SettingsFactory:257 - Query cache: disabled
17:42:28,424 INFO SettingsFactory:382 - Cache region factory : org.hibernate.cache.impl.NoCachingRegionFactory
17:42:28,424 INFO SettingsFactory:267 - Optimize cache for minimal puts: disabled
17:42:28,424 INFO SettingsFactory:276 - Structured second-level cache entries: disabled
17:42:28,429 INFO SettingsFactory:296 - Echoing all SQL to stdout
17:42:28,429 INFO SettingsFactory:305 - Statistics: disabled
17:42:28,430 INFO SettingsFactory:309 - Deleted entity synthetic identifier rollback: disabled
17:42:28,430 INFO SettingsFactory:324 - Default entity-mode: pojo
17:42:28,430 INFO SettingsFactory:328 - Named query checking : enabled
17:42:28,464 INFO SessionFactoryImpl:193 - building session factory
17:42:28,465 DEBUG SessionFactoryImpl:211 - Session factory constructed with filter configurations : {}
17:42:28,465 DEBUG SessionFactoryImpl:215 - instantiating session factory with properties: {java.runtime.name=Java(TM) SE Runtime Environment, hibernate.connection.password=mywebsite, sun.boot.library.path=C:\Program Files\Java\jre6\bin, java.vm.version=16.3-b01, hibernate.connection.username=amoghsa, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) 64-Bit Server VM, file.encoding.pkg=sun.io, user.country=IN, sun.java.launcher=SUN_STANDARD, sun.os.patch.level=, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\Amogh_WS\FootieCal\FootieCal, java.runtime.version=1.6.0_20-b02, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, hbm2ddl.auto=update, java.endorsed.dirs=C:\Program Files\Java\jre6\lib\endorsed, os.arch=amd64, java.io.tmpdir=C:\Users\Dell\AppData\Local\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows 7, sun.jnu.encoding=Cp1252, java.library.path=C:\Program Files\Java\jre6\bin;.;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\oraclexe\app\oracle\product\10.2.0\server\bin;C:\Program Files (x86)\PC Connectivity Solution\;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files\Dell\DW WLAN Card;c:\Program Files\WIDCOMM\Bluetooth Software\;c:\Program Files\WIDCOMM\Bluetooth Software\syswow64;c:\Program Files (x86)\ATI Technologies\ATI.ACE\Core-Static;c:\Program Files (x86)\Common Files\Roxio Shared\DLLShared\;C:\Program Files (x86)\QuickTime\QTSystem\, java.specification.name=Java Platform API Specification, java.class.version=50.0, sun.management.compiler=HotSpot 64-Bit Server Compiler, os.version=6.1, user.home=C:\Users\Dell, user.timezone=Asia/Calcutta, java.awt.printerjob=sun.awt.windows.WPrinterJob, java.specification.version=1.6, file.encoding=Cp1252, hibernate.connection.driver_class=oracle.jdbc.driver.OracleDriver, show_sql=true, java.class.path=C:\Amogh_WS\FootieCal\FootieCal\build\classes;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\antlr-2.7.6.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\commons-collections-3.1.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\dom4j-1.6.1.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\ejb3-persistence.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\hibernate-annotations.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\hibernate-commons-annotations.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\hibernate-entitymanager.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\hibernate3.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\javassist-3.9.0.GA.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\jta-1.1.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\jxl.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\log4j-1.2.15.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\ojdbc14.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\slf4j-api-1.5.8.jar;C:\Amogh_WS\FootieCal\FootieCal\WebContent\WEB-INF\lib\slf4j-log4j12-1.5.8.jar, user.name=Dell, hibernate.bytecode.use_reflection_optimizer=false, hibernate.show_sql=true, java.vm.specification.version=1.0, sun.arch.data.model=64, java.home=C:\Program Files\Java\jre6, hibernate.connection.url=jdbc:oracle:thin:@localhost:1521:XE, hibernate.dialect=org.hibernate.dialect.Oracle10gDialect, java.specification.vendor=Sun Microsystems Inc., user.language=en, awt.toolkit=sun.awt.windows.WToolkit, java.vm.info=mixed mode, java.version=1.6.0_20, java.ext.dirs=C:\Program Files\Java\jre6\lib\ext;C:\Windows\Sun\Java\lib\ext, sun.boot.class.path=C:\Program Files\Java\jre6\lib\resources.jar;C:\Program Files\Java\jre6\lib\rt.jar;C:\Program Files\Java\jre6\lib\sunrsasign.jar;C:\Program Files\Java\jre6\lib\jsse.jar;C:\Program Files\Java\jre6\lib\jce.jar;C:\Program Files\Java\jre6\lib\charsets.jar;C:\Program Files\Java\jre6\classes, java.vendor=Sun Microsystems Inc., file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, hibernate.hbm2ddl.auto=update, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, sun.desktop=windows, sun.cpu.isalist=amd64}
17:42:28,587 DEBUG AbstractEntityPersister:2756 - Static SQL for entity: com.amoghsweb.footiecal.GameData
17:42:28,587 DEBUG AbstractEntityPersister:2761 - Version select: select GAME_ID from GAME_DATA where GAME_ID =?
17:42:28,587 DEBUG AbstractEntityPersister:2764 - Snapshot select: select gamedata_.GAME_ID, gamedata_.GAME_DATE as GAME2_0_, gamedata_.PLACE as PLACE0_, gamedata_.RESULT as RESULT0_, gamedata_.TEAM1 as TEAM5_0_, gamedata_.TEAM2 as TEAM6_0_ from GAME_DATA gamedata_ where gamedata_.GAME_ID=?
17:42:28,587 DEBUG AbstractEntityPersister:2767 - Insert 0: insert into GAME_DATA (GAME_DATE, PLACE, RESULT, TEAM1, TEAM2, GAME_ID) values (?, ?, ?, ?, ?, ?)
17:42:28,588 DEBUG AbstractEntityPersister:2768 - Update 0: update GAME_DATA set GAME_DATE=?, PLACE=?, RESULT=?, TEAM1=?, TEAM2=? where GAME_ID=?
17:42:28,588 DEBUG AbstractEntityPersister:2769 - Delete 0: delete from GAME_DATA where GAME_ID=?
17:42:28,604 DEBUG EntityLoader:102 - Static select for entity com.amoghsweb.footiecal.GameData: select gamedata0_.GAME_ID as GAME1_0_0_, gamedata0_.GAME_DATE as GAME2_0_0_, gamedata0_.PLACE as PLACE0_0_, gamedata0_.RESULT as RESULT0_0_, gamedata0_.TEAM1 as TEAM5_0_0_, gamedata0_.TEAM2 as TEAM6_0_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_ID=?
17:42:28,604 DEBUG EntityLoader:102 - Static select for entity com.amoghsweb.footiecal.GameData: select gamedata0_.GAME_ID as GAME1_0_0_, gamedata0_.GAME_DATE as GAME2_0_0_, gamedata0_.PLACE as PLACE0_0_, gamedata0_.RESULT as RESULT0_0_, gamedata0_.TEAM1 as TEAM5_0_0_, gamedata0_.TEAM2 as TEAM6_0_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_ID=?
17:42:28,605 DEBUG EntityLoader:102 - Static select for entity com.amoghsweb.footiecal.GameData: select gamedata0_.GAME_ID as GAME1_0_0_, gamedata0_.GAME_DATE as GAME2_0_0_, gamedata0_.PLACE as PLACE0_0_, gamedata0_.RESULT as RESULT0_0_, gamedata0_.TEAM1 as TEAM5_0_0_, gamedata0_.TEAM2 as TEAM6_0_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_ID=? for update
17:42:28,605 DEBUG EntityLoader:102 - Static select for entity com.amoghsweb.footiecal.GameData: select gamedata0_.GAME_ID as GAME1_0_0_, gamedata0_.GAME_DATE as GAME2_0_0_, gamedata0_.PLACE as PLACE0_0_, gamedata0_.RESULT as RESULT0_0_, gamedata0_.TEAM1 as TEAM5_0_0_, gamedata0_.TEAM2 as TEAM6_0_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_ID=? for update nowait
17:42:28,605 DEBUG EntityLoader:102 - Static select for entity com.amoghsweb.footiecal.GameData: select gamedata0_.GAME_ID as GAME1_0_0_, gamedata0_.GAME_DATE as GAME2_0_0_, gamedata0_.PLACE as PLACE0_0_, gamedata0_.RESULT as RESULT0_0_, gamedata0_.TEAM1 as TEAM5_0_0_, gamedata0_.TEAM2 as TEAM6_0_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_ID=? for update nowait
17:42:28,611 DEBUG EntityLoader:57 - Static select for action ACTION_MERGE on entity com.amoghsweb.footiecal.GameData: select gamedata0_.GAME_ID as GAME1_0_0_, gamedata0_.GAME_DATE as GAME2_0_0_, gamedata0_.PLACE as PLACE0_0_, gamedata0_.RESULT as RESULT0_0_, gamedata0_.TEAM1 as TEAM5_0_0_, gamedata0_.TEAM2 as TEAM6_0_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_ID=?
17:42:28,611 DEBUG EntityLoader:57 - Static select for action ACTION_REFRESH on entity com.amoghsweb.footiecal.GameData: select gamedata0_.GAME_ID as GAME1_0_0_, gamedata0_.GAME_DATE as GAME2_0_0_, gamedata0_.PLACE as PLACE0_0_, gamedata0_.RESULT as RESULT0_0_, gamedata0_.TEAM1 as TEAM5_0_0_, gamedata0_.TEAM2 as TEAM6_0_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_ID=?
17:42:28,613 DEBUG SessionFactoryObjectFactory:62 - initializing class SessionFactoryObjectFactory
17:42:28,614 DEBUG SessionFactoryObjectFactory:99 - registered: 402881842ba5817d012ba5817e440000 (unnamed)
17:42:28,614 INFO SessionFactoryObjectFactory:105 - Not binding factory to JNDI, no JNDI name configured
17:42:28,614 DEBUG SessionFactoryImpl:350 - instantiated session factory
17:42:28,619 INFO SchemaUpdate:155 - Running hbm2ddl schema update
17:42:28,619 INFO SchemaUpdate:167 - fetching database metadata
17:42:28,705 INFO SchemaUpdate:179 - updating schema
17:42:28,705 DEBUG AnnotationConfiguration:258 - Execute first pass mapping processing
17:42:28,706 DEBUG AnnotationConfiguration:529 - Process hbm files
17:42:28,706 DEBUG AnnotationConfiguration:537 - Process annotated classes
17:42:28,706 DEBUG AnnotationConfiguration:401 - processing fk mappings (*ToOne and JoinedSubclass)
17:42:28,706 DEBUG Configuration:1167 - processing extends queue
17:42:28,706 DEBUG Configuration:1171 - processing collection mappings
17:42:28,706 DEBUG Configuration:1182 - processing native query and ResultSetMapping mappings
17:42:28,707 DEBUG Configuration:1190 - processing association property references
17:42:28,707 DEBUG Configuration:1212 - processing foreign key constraints
17:42:28,787 INFO TableMetadata:65 - table found: AMOGHSA.GAME_DATA
17:42:28,788 INFO TableMetadata:66 - columns: [result, game_date, game_id, place, team1, team2]
17:42:28,788 INFO TableMetadata:68 - foreign keys: []
17:42:28,788 INFO TableMetadata:69 - indexes: [pk_gameid]
17:42:28,788 INFO SchemaUpdate:217 - schema update complete
17:42:28,789 DEBUG SessionFactoryImpl:474 - Checking 0 named HQL queries
17:42:28,790 DEBUG SessionFactoryImpl:494 - Checking 0 named SQL queries
17:42:28,815 DEBUG SessionImpl:248 - opened session at timestamp: 12869719487
17:42:28,816 DEBUG JDBCTransaction:82 - begin
17:42:28,817 DEBUG ConnectionManager:444 - opening JDBC connection
17:42:28,817 DEBUG JDBCTransaction:87 - current autocommit status: false
1911-10-01 02:02:02.000000002
17:42:28,851 DEBUG QueryTranslatorImpl:272 - parse() - HQL: from com.amoghsweb.footiecal.GameData as games where games.gameDate > :mindate and games.gameDate < :maxdate
17:42:28,862 DEBUG AST:291 - --- HQL AST ---
\-[QUERY] 'query'
+-[SELECT_FROM] 'SELECT_FROM'
| \-[FROM] 'from'
| \-[RANGE] 'RANGE'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[DOT] '.'
| | | | +-[IDENT] 'com'
| | | | \-[IDENT] 'amoghsweb'
| | | \-[IDENT] 'footiecal'
| | \-[IDENT] 'GameData'
| \-[ALIAS] 'games'
\-[WHERE] 'where'
\-[AND] 'and'
+-[GT] '>'
| +-[DOT] '.'
| | +-[IDENT] 'games'
| | \-[IDENT] 'gameDate'
| \-[COLON] ':'
| \-[IDENT] 'mindate'
\-[LT] '<'
+-[DOT] '.'
| +-[IDENT] 'games'
| \-[IDENT] 'gameDate'
\-[COLON] ':'
\-[IDENT] 'maxdate'

17:42:28,862 DEBUG ErrorCounter:91 - throwQueryException() : no errors
17:42:28,889 DEBUG HqlSqlBaseWalker:111 - select << begin [level=1, statement=select]
17:42:28,902 DEBUG FromElement:132 - FromClause{level=1} : com.amoghsweb.footiecal.GameData (games) -> gamedata0_
17:42:28,904 DEBUG FromReferenceNode:74 - Resolved : games -> gamedata0_.GAME_ID
17:42:28,905 DEBUG DotNode:619 - getDataType() : gameDate -> org.hibernate.type.TimestampType@64a7c45e
17:42:28,906 DEBUG FromReferenceNode:74 - Resolved : games.gameDate -> gamedata0_.GAME_DATE
17:42:28,909 DEBUG FromReferenceNode:74 - Resolved : games -> gamedata0_.GAME_ID
17:42:28,909 DEBUG DotNode:619 - getDataType() : gameDate -> org.hibernate.type.TimestampType@64a7c45e
17:42:28,910 DEBUG FromReferenceNode:74 - Resolved : games.gameDate -> gamedata0_.GAME_DATE
17:42:28,910 DEBUG HqlSqlBaseWalker:117 - select : finishing up [level=1, statement=select]
17:42:28,911 DEBUG HqlSqlWalker:599 - processQuery() : ( SELECT ( FromClause{level=1} GAME_DATA gamedata0_ ) ( where ( and ( > ( gamedata0_.GAME_DATE gamedata0_.GAME_ID gameDate ) ? ) ( < ( gamedata0_.GAME_DATE gamedata0_.GAME_ID gameDate ) ? ) ) ) )
17:42:28,916 DEBUG HqlSqlWalker:842 - Derived SELECT clause created.
17:42:28,919 DEBUG JoinProcessor:176 - Using FROM fragment [GAME_DATA gamedata0_]
17:42:28,919 DEBUG HqlSqlBaseWalker:123 - select >> end [level=1, statement=select]
17:42:28,921 DEBUG AST:258 - --- SQL AST ---
\-[SELECT] QueryNode: 'SELECT' querySpaces (GAME_DATA)
+-[SELECT_CLAUSE] SelectClause: '{derived select clause}'
| +-[SELECT_EXPR] SelectExpressionImpl: 'gamedata0_.GAME_ID as GAME1_0_'
| \-[SQL_TOKEN] SqlFragment: 'gamedata0_.GAME_DATE as GAME2_0_, gamedata0_.PLACE as PLACE0_, gamedata0_.RESULT as RESULT0_, gamedata0_.TEAM1 as TEAM5_0_, gamedata0_.TEAM2 as TEAM6_0_'
+-[FROM] FromClause: 'from' FromClause{level=1, fromElementCounter=1, fromElements=1, fromElementByClassAlias=[games], fromElementByTableAlias=[gamedata0_], fromElementsByPath=[], collectionJoinFromElementsByPath=[], impliedElements=[]}
| \-[FROM_FRAGMENT] FromElement: 'GAME_DATA gamedata0_' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=games,role=null,tableName=GAME_DATA,tableAlias=gamedata0_,origin=null,colums={,className=com.amoghsweb.footiecal.GameData}}
\-[WHERE] SqlNode: 'where'
\-[AND] SqlNode: 'and'
+-[GT] BinaryLogicOperatorNode: '>'
| +-[DOT] DotNode: 'gamedata0_.GAME_DATE'
| | +-[ALIAS_REF] IdentNode: 'gamedata0_.GAME_ID'
| | \-[IDENT] IdentNode: 'gameDate'

| \-[NAMED_PARAM] ParameterNode: '?' {name=mindate, expectedType=org.hibernate.type.TimestampType@64a7c45e}

\-[LT] BinaryLogicOperatorNode: '<'
+-[DOT] DotNode: 'gamedata0_.GAME_DATE'
| +-[ALIAS_REF] IdentNode: 'gamedata0_.GAME_ID'
| \-[IDENT] IdentNode: 'gameDate'

\-[NAMED_PARAM] ParameterNode: '?' {name=maxdate, expectedType=org.hibernate.type.TimestampType@64a7c45e}

17:42:28,921 DEBUG ErrorCounter:91 - throwQueryException() : no errors
17:42:28,932 DEBUG QueryTranslatorImpl:241 - HQL: from com.amoghsweb.footiecal.GameData as games where games.gameDate > :mindate and games.gameDate < :maxdate
17:42:28,932 DEBUG QueryTranslatorImpl:242 - SQL: select gamedata0_.GAME_ID as GAME1_0_, gamedata0_.GAME_DATE as GAME2_0_, gamedata0_.PLACE as PLACE0_, gamedata0_.RESULT as RESULT0_, gamedata0_.TEAM1 as TEAM5_0_, gamedata0_.TEAM2 as TEAM6_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_DATE>? and gamedata0_.GAME_DATE<?
17:42:28,932 DEBUG ErrorCounter:91 - throwQueryException() : no errors
17:42:28,943 DEBUG AbstractBatcher:410 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
17:42:28,943 DEBUG SQL:111 - select gamedata0_.GAME_ID as GAME1_0_, gamedata0_.GAME_DATE as GAME2_0_, gamedata0_.PLACE as PLACE0_, gamedata0_.RESULT as RESULT0_, gamedata0_.TEAM1 as TEAM5_0_, gamedata0_.TEAM2 as TEAM6_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_DATE>? and gamedata0_.GAME_DATE<?
Hibernate: select gamedata0_.GAME_ID as GAME1_0_, gamedata0_.GAME_DATE as GAME2_0_, gamedata0_.PLACE as PLACE0_, gamedata0_.RESULT as RESULT0_, gamedata0_.TEAM1 as TEAM5_0_, gamedata0_.TEAM2 as TEAM6_0_ from GAME_DATA gamedata0_ where gamedata0_.GAME_DATE>? and gamedata0_.GAME_DATE<?
17:42:28,948 DEBUG AbstractBatcher:426 - about to open ResultSet (open ResultSets: 0, globally: 0)
17:42:28,948 DEBUG AbstractBatcher:433 - about to close ResultSet (open ResultSets: 1, globally: 1)
17:42:28,949 DEBUG AbstractBatcher:418 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
17:42:28,950 DEBUG StatefulPersistenceContext:860 - initializing non-lazy collections
[]
17:42:28,950 DEBUG JDBCTransaction:134 - commit
17:42:28,951 DEBUG JDBCTransaction:147 - committed JDBC Connection
17:42:28,951 DEBUG ConnectionManager:427 - aggressively releasing JDBC connection
17:42:28,951 DEBUG ConnectionManager:464 - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]