Hi!
I generated an simple HQL query:
Code:
from Subscription sub where
(sub.beginTime=null and sub.endTime=null) or
(sub.beginTime>=:now and sub.endTime=null) or
(sub.beginTime>=:now and sub.endTime<=:now) or
(sub.beginTime=null and sub.endTime<=:now)
SQL generated by Hibernate is missing those parentheses separating "or"-operators:
Code:
from NC_Subscription subscripti0_
where
(subscripti0_.SUB_BEGINTIME is null) and (subscripti0_.SUB_ENDTIME is null) or
subscripti0_.SUB_BEGINTIME>=? and (subscripti0_.SUB_ENDTIME is null) or
subscripti0_.SUB_BEGINTIME>=? and subscripti0_.SUB_ENDTIME<=? or
(subscripti0_.SUB_BEGINTIME is null) and subscripti0_.SUB_ENDTIME<=?
Query result becomes incorrect. Returns rows which do not match intented restriction.
Hibernate bug or have I misunderstood something?
Hibernate version: 3.0.5
Mapping documents:Code:
<class name="Subscription" table="Subscription" proxy="Subscription">
<id name="id" column="SUB_PK_OID">
<generator class="sequence">
<param name="sequence">SUB_PK_OID_SEQUENCE</param>
</generator>
</id>
<many-to-one name="licensedUser" column="SUB_USR_OID"
class="LicensedUser" not-null="true"/>
<many-to-one name="service" column="SUB_SRV_OID"
class="Service" not-null="true"/>
<property name="beginTime" column="SUB_BEGINTIME"/>
<property name="endTime" column="SUB_ENDTIME"/>
<many-to-one name="subscribeableService" column="SUB_SSE_OID"
class="SubscribeableService"/>
</class>
Code between sessionFactory.openSession() and session.close():Code:
StringBuffer hqlTest = new StringBuffer();
hqlTest.append("from Subscription sub where ");
hqlTest.append("(sub.beginTime=null and sub.endTime=null) or");
hqlTest.append("(sub.beginTime>=:now and sub.endTime=null) or");
hqlTest.append("(sub.beginTime>=:now and sub.endTime<=:now) or");
hqlTest.append("(sub.beginTime=null and sub.endTime<=:now)");
List lst = session.createQuery( hqlTest.toString() )
.setTimestamp("now", new Date()).list();
Name and version of the database you are using: Oracle 10.1.0.2.0
The generated SQL (show_sql=true):Code:
select subscripti0_.SUB_PK_OID as SUB1_, subscripti0_.SUB_USR_OID as SUB2_20_,
subscripti0_.SUB_SRV_OID as SUB3_20_, subscripti0_.SUB_BEGINTIME as SUB4_20_,
subscripti0_.SUB_ENDTIME as SUB5_20_, subscripti0_.SUB_SSE_OID as SUB6_20_
from NC_Subscription subscripti0_
where
(subscripti0_.SUB_BEGINTIME is null) and
(subscripti0_.SUB_ENDTIME is null) or
subscripti0_.SUB_BEGINTIME>=? and
(subscripti0_.SUB_ENDTIME is null) or
subscripti0_.SUB_BEGINTIME>=? and
subscripti0_.SUB_ENDTIME<=? or
(subscripti0_.SUB_BEGINTIME is null) and
subscripti0_.SUB_ENDTIME<=?
Debug level Hibernate log excerpt:
2005-08-12 16:22:33,582 DEBUG [org.hibernate.impl.SessionImpl] find: from Subscription sub where (sub.beginTime=null and sub.endTime=null) or(sub.beginTime>=:now and sub.endTime=null) or(sub.beginTime>=:now and sub.endTime<=:now) or(sub.beginTime=null and sub.endTime<=:now)
2005-08-12 16:22:33,582 DEBUG [org.hibernate.engine.QueryParameters] named parameters: {now=2005-08-12 13:22:33}
2005-08-12 16:22:33,691 DEBUG [org.hibernate.hql.ast.QueryTranslatorImpl] parse() - HQL: from Subscription sub where (sub.beginTime=null and sub.endTime=null) or(sub.beginTime>=:now and sub.endTime=null) or(sub.beginTime>=:now and sub.endTime<=:now) or(sub.beginTime=null and sub.endTime<=:now)
2005-08-12 16:22:33,723 DEBUG [org.hibernate.hql.ast.AST] --- HQL AST ---
\-[QUERY] 'query'
+-[SELECT_FROM] 'SELECT_FROM'
| \-[FROM] 'from'
| \-[RANGE] 'RANGE'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[DOT] '.'
| | | | +-[DOT] '.'
| | | | | +-[IDENT] 'com'
| | | | | \-[IDENT] 'navicoretech'
| | | | \-[IDENT] 'contentbroker'
| | | \-[IDENT] 'domain'
| | \-[IDENT] 'Subscription'
| \-[ALIAS] 'sub'
\-[WHERE] 'where'
\-[OR] 'or'
+-[OR] 'or'
| +-[OR] 'or'
| | +-[AND] 'and'
| | | +-[IS_NULL] 'is null'
| | | | \-[DOT] '.'
| | | | +-[IDENT] 'sub'
| | | | \-[IDENT] 'beginTime'
| | | \-[IS_NULL] 'is null'
| | | \-[DOT] '.'
| | | +-[IDENT] 'sub'
| | | \-[IDENT] 'endTime'
| | \-[AND] 'and'
| | +-[GE] '>='
| | | +-[DOT] '.'
| | | | +-[IDENT] 'sub'
| | | | \-[IDENT] 'beginTime'
| | | \-[COLON] ':'
| | | \-[IDENT] 'now'
| | \-[IS_NULL] 'is null'
| | \-[DOT] '.'
| | +-[IDENT] 'sub'
| | \-[IDENT] 'endTime'
| \-[AND] 'and'
| +-[GE] '>='
| | +-[DOT] '.'
| | | +-[IDENT] 'sub'
| | | \-[IDENT] 'beginTime'
| | \-[COLON] ':'
| | \-[IDENT] 'now'
| \-[LE] '<='
| +-[DOT] '.'
| | +-[IDENT] 'sub'
| | \-[IDENT] 'endTime'
| \-[COLON] ':'
| \-[IDENT] 'now'
\-[AND] 'and'
+-[IS_NULL] 'is null'
| \-[DOT] '.'
| +-[IDENT] 'sub'
| \-[IDENT] 'beginTime'
\-[LE] '<='
+-[DOT] '.'
| +-[IDENT] 'sub'
| \-[IDENT] 'endTime'
\-[COLON] ':'
\-[IDENT] 'now'
2005-08-12 16:22:33,723 DEBUG [org.hibernate.hql.ast.ErrorCounter] throwQueryException() : no errors
2005-08-12 16:22:33,770 DEBUG [org.hibernate.hql.antlr.HqlSqlBaseWalker] query() << begin, level = 1
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromElement] FromClause{level=1} : Subscription (sub) -> subscripti0_
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub -> subscripti0_.SUB_PK_OID
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.DotNode] getDataType() : beginTime -> org.hibernate.type.TimestampType@76d4af
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub.beginTime -> subscripti0_.SUB_BEGINTIME
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub -> subscripti0_.SUB_PK_OID
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.DotNode] getDataType() : endTime -> org.hibernate.type.TimestampType@76d4af
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub.endTime -> subscripti0_.SUB_ENDTIME
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub -> subscripti0_.SUB_PK_OID
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.DotNode] getDataType() : beginTime -> org.hibernate.type.TimestampType@76d4af
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub.beginTime -> subscripti0_.SUB_BEGINTIME
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub -> subscripti0_.SUB_PK_OID
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.DotNode] getDataType() : endTime -> org.hibernate.type.TimestampType@76d4af
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub.endTime -> subscripti0_.SUB_ENDTIME
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub -> subscripti0_.SUB_PK_OID
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.DotNode] getDataType() : beginTime -> org.hibernate.type.TimestampType@76d4af
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub.beginTime -> subscripti0_.SUB_BEGINTIME
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub -> subscripti0_.SUB_PK_OID
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.DotNode] getDataType() : endTime -> org.hibernate.type.TimestampType@76d4af
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub.endTime -> subscripti0_.SUB_ENDTIME
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub -> subscripti0_.SUB_PK_OID
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.DotNode] getDataType() : beginTime -> org.hibernate.type.TimestampType@76d4af
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub.beginTime -> subscripti0_.SUB_BEGINTIME
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub -> subscripti0_.SUB_PK_OID
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.DotNode] getDataType() : endTime -> org.hibernate.type.TimestampType@76d4af
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.FromReferenceNode] Resolved : sub.endTime -> subscripti0_.SUB_ENDTIME
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.antlr.HqlSqlBaseWalker] query() : finishing up , level = 1
2005-08-12 16:22:33,801 DEBUG [org.hibernate.hql.ast.HqlSqlWalker] processQuery() : ( SELECT ( FromClause{level=1} NC_Subscription subscripti0_ ) ( where ( or ( or ( or ( and ( is null ( subscripti0_.SUB_BEGINTIME subscripti0_.SUB_PK_OID beginTime ) ) ( is null ( subscripti0_.SUB_ENDTIME subscripti0_.SUB_PK_OID endTime ) ) ) ( and ( >= ( subscripti0_.SUB_BEGINTIME subscripti0_.SUB_PK_OID beginTime ) ? ) ( is null ( subscripti0_.SUB_ENDTIME subscripti0_.SUB_PK_OID endTime ) ) ) ) ( and ( >= ( subscripti0_.SUB_BEGINTIME subscripti0_.SUB_PK_OID beginTime ) ? ) ( <= ( subscripti0_.SUB_ENDTIME subscripti0_.SUB_PK_OID endTime ) ? ) ) ) ( and ( is null ( subscripti0_.SUB_BEGINTIME subscripti0_.SUB_PK_OID beginTime ) ) ( <= ( subscripti0_.SUB_ENDTIME subscripti0_.SUB_PK_OID endTime ) ? ) ) ) ) )
2005-08-12 16:22:33,816 DEBUG [org.hibernate.hql.ast.HqlSqlWalker] Derived SELECT clause created.
2005-08-12 16:22:33,832 DEBUG [org.hibernate.hql.ast.JoinProcessor] Using FROM fragment [NC_Subscription subscripti0_]
2005-08-12 16:22:33,832 DEBUG [org.hibernate.hql.antlr.HqlSqlBaseWalker] query() >> end, level = 1
2005-08-12 16:22:33,832 DEBUG [org.hibernate.hql.ast.AST] --- SQL AST ---
\-[SELECT] QueryNode: 'SELECT' querySpaces (NC_Subscription)
+-[SELECT_CLAUSE] SelectClause: '{derived select clause}'
| +-[SELECT_EXPR] SelectExpressionImpl: 'subscripti0_.SUB_PK_OID as SUB1_' {FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=sub,role=null,tableName=NC_Subscription,tableAlias=subscripti0_,colums={,className=Subscription}}}
| \-[SQL_TOKEN] SqlFragment: 'subscripti0_.SUB_USR_OID as SUB2_20_, subscripti0_.SUB_SRV_OID as SUB3_20_, subscripti0_.SUB_BEGINTIME as SUB4_20_, subscripti0_.SUB_ENDTIME as SUB5_20_, subscripti0_.SUB_SSE_OID as SUB6_20_'
+-[FROM] FromClause: 'from' FromClause{level=1, fromElementCounter=1, fromElements=1, fromElementByClassAlias=[sub], fromElementByTableAlias=[subscripti0_], fromElementsByPath=[], collectionJoinFromElementsByPath=[], impliedElements=[]}
| \-[FROM_FRAGMENT] FromElement: 'NC_Subscription subscripti0_' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=sub,role=null,tableName=NC_Subscription,tableAlias=subscripti0_,colums={,className=Subscription}}
\-[WHERE] SqlNode: 'where'
\-[OR] SqlNode: 'or'
+-[OR] SqlNode: 'or'
| +-[OR] SqlNode: 'or'
| | +-[AND] SqlNode: 'and'
| | | +-[IS_NULL] SqlNode: 'is null'
| | | | \-[DOT] DotNode: 'subscripti0_.SUB_BEGINTIME' {propertyName=beginTime,dereferenceType=4,propertyPath=beginTime,path=sub.beginTime,tableAlias=subscripti0_,className=Subscription,classAlias=sub}
| | | | +-[ALIAS_REF] IdentNode: 'subscripti0_.SUB_PK_OID' {alias=sub, className=Subscription, tableAlias=subscripti0_}
| | | | \-[IDENT] IdentNode: 'beginTime' {originalText=beginTime}
| | | \-[IS_NULL] SqlNode: 'is null'
| | | \-[DOT] DotNode: 'subscripti0_.SUB_ENDTIME' {propertyName=endTime,dereferenceType=4,propertyPath=endTime,path=sub.endTime,tableAlias=subscripti0_,className=Subscription,classAlias=sub}
| | | +-[ALIAS_REF] IdentNode: 'subscripti0_.SUB_PK_OID' {alias=sub, className=Subscription, tableAlias=subscripti0_}
| | | \-[IDENT] IdentNode: 'endTime' {originalText=endTime}
| | \-[AND] SqlNode: 'and'
| | +-[GE] SqlNode: '>='
| | | +-[DOT] DotNode: 'subscripti0_.SUB_BEGINTIME' {propertyName=beginTime,dereferenceType=4,propertyPath=beginTime,path=sub.beginTime,tableAlias=subscripti0_,className=Subscription,classAlias=sub}
| | | | +-[ALIAS_REF] IdentNode: 'subscripti0_.SUB_PK_OID' {alias=sub, className=Subscription, tableAlias=subscripti0_}
| | | | \-[IDENT] IdentNode: 'beginTime' {originalText=beginTime}
| | | \-[NAMED_PARAM] SqlNode: '?'
| | \-[IS_NULL] SqlNode: 'is null'
| | \-[DOT] DotNode: 'subscripti0_.SUB_ENDTIME' {propertyName=endTime,dereferenceType=4,propertyPath=endTime,path=sub.endTime,tableAlias=subscripti0_,className=Subscription,classAlias=sub}
| | +-[ALIAS_REF] IdentNode: 'subscripti0_.SUB_PK_OID' {alias=sub, className=Subscription, tableAlias=subscripti0_}
| | \-[IDENT] IdentNode: 'endTime' {originalText=endTime}
| \-[AND] SqlNode: 'and'
| +-[GE] SqlNode: '>='
| | +-[DOT] DotNode: 'subscripti0_.SUB_BEGINTIME' {propertyName=beginTime,dereferenceType=4,propertyPath=beginTime,path=sub.beginTime,tableAlias=subscripti0_,className=Subscription,classAlias=sub}
| | | +-[ALIAS_REF] IdentNode: 'subscripti0_.SUB_PK_OID' {alias=sub, className=Subscription, tableAlias=subscripti0_}
| | | \-[IDENT] IdentNode: 'beginTime' {originalText=beginTime}
| | \-[NAMED_PARAM] SqlNode: '?'
| \-[LE] SqlNode: '<='
| +-[DOT] DotNode: 'subscripti0_.SUB_ENDTIME' {propertyName=endTime,dereferenceType=4,propertyPath=endTime,path=sub.endTime,tableAlias=subscripti0_,className=Subscription,classAlias=sub}
| | +-[ALIAS_REF] IdentNode: 'subscripti0_.SUB_PK_OID' {alias=sub, className=Subscription, tableAlias=subscripti0_}
| | \-[IDENT] IdentNode: 'endTime' {originalText=endTime}
| \-[NAMED_PARAM] SqlNode: '?'
\-[AND] SqlNode: 'and'
+-[IS_NULL] SqlNode: 'is null'
| \-[DOT] DotNode: 'subscripti0_.SUB_BEGINTIME' {propertyName=beginTime,dereferenceType=4,propertyPath=beginTime,path=sub.beginTime,tableAlias=subscripti0_,className=Subscription,classAlias=sub}
| +-[ALIAS_REF] IdentNode: 'subscripti0_.SUB_PK_OID' {alias=sub, className=Subscription, tableAlias=subscripti0_}
| \-[IDENT] IdentNode: 'beginTime' {originalText=beginTime}
\-[LE] SqlNode: '<='
+-[DOT] DotNode: 'subscripti0_.SUB_ENDTIME' {propertyName=endTime,dereferenceType=4,propertyPath=endTime,path=sub.endTime,tableAlias=subscripti0_,className=Subscription,classAlias=sub}
| +-[ALIAS_REF] IdentNode: 'subscripti0_.SUB_PK_OID' {alias=sub, className=Subscription, tableAlias=subscripti0_}
| \-[IDENT] IdentNode: 'endTime' {originalText=endTime}
\-[NAMED_PARAM] SqlNode: '?'
2005-08-12 16:22:33,832 DEBUG [org.hibernate.hql.ast.ErrorCounter] throwQueryException() : no errors
2005-08-12 16:22:33,848 DEBUG [org.hibernate.hql.ast.QueryTranslatorImpl] HQL: from Subscription sub where (sub.beginTime=null and sub.endTime=null) or(sub.beginTime>=:now and sub.endTime=null) or(sub.beginTime>=:now and sub.endTime<=:now) or(sub.beginTime=null and sub.endTime<=:now)
2005-08-12 16:22:33,848 DEBUG [org.hibernate.hql.ast.QueryTranslatorImpl] SQL: select subscripti0_.SUB_PK_OID as SUB1_, subscripti0_.SUB_USR_OID as SUB2_20_, subscripti0_.SUB_SRV_OID as SUB3_20_, subscripti0_.SUB_BEGINTIME as SUB4_20_, subscripti0_.SUB_ENDTIME as SUB5_20_, subscripti0_.SUB_SSE_OID as SUB6_20_ from NC_Subscription subscripti0_ where (subscripti0_.SUB_BEGINTIME is null) and (subscripti0_.SUB_ENDTIME is null) or subscripti0_.SUB_BEGINTIME>=? and (subscripti0_.SUB_ENDTIME is null) or subscripti0_.SUB_BEGINTIME>=? and subscripti0_.SUB_ENDTIME<=? or (subscripti0_.SUB_BEGINTIME is null) and subscripti0_.SUB_ENDTIME<=?
2005-08-12 16:22:33,848 DEBUG [org.hibernate.hql.ast.ErrorCounter] throwQueryException() : no errors
2005-08-12 16:22:33,863 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2005-08-12 16:22:33,863 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection
2005-08-12 16:22:33,863 DEBUG [org.hibernate.SQL] select subscripti0_.SUB_PK_OID as SUB1_, subscripti0_.SUB_USR_OID as SUB2_20_, subscripti0_.SUB_SRV_OID as SUB3_20_, subscripti0_.SUB_BEGINTIME as SUB4_20_, subscripti0_.SUB_ENDTIME as SUB5_20_, subscripti0_.SUB_SSE_OID as SUB6_20_ from NC_Subscription subscripti0_ where (subscripti0_.SUB_BEGINTIME is null) and (subscripti0_.SUB_ENDTIME is null) or subscripti0_.SUB_BEGINTIME>=? and (subscripti0_.SUB_ENDTIME is null) or subscripti0_.SUB_BEGINTIME>=? and subscripti0_.SUB_ENDTIME<=? or (subscripti0_.SUB_BEGINTIME is null) and subscripti0_.SUB_ENDTIME<=?
2005-08-12 16:22:33,863 INFO [STDOUT] Hibernate: select subscripti0_.SUB_PK_OID as SUB1_, subscripti0_.SUB_USR_OID as SUB2_20_, subscripti0_.SUB_SRV_OID as SUB3_20_, subscripti0_.SUB_BEGINTIME as SUB4_20_, subscripti0_.SUB_ENDTIME as SUB5_20_, subscripti0_.SUB_SSE_OID as SUB6_20_ from NC_Subscription subscripti0_ where (subscripti0_.SUB_BEGINTIME is null) and (subscripti0_.SUB_ENDTIME is null) or subscripti0_.SUB_BEGINTIME>=? and (subscripti0_.SUB_ENDTIME is null) or subscripti0_.SUB_BEGINTIME>=? and subscripti0_.SUB_ENDTIME<=? or (subscripti0_.SUB_BEGINTIME is null) and subscripti0_.SUB_ENDTIME<=?
2005-08-12 16:22:33,863 DEBUG [org.hibernate.jdbc.AbstractBatcher] preparing statement
2005-08-12 16:22:33,863 DEBUG [org.hibernate.loader.hql.QueryLoader] bindNamedParameters() Fri Aug 12 13:22:33 GMT 2005 -> now [1]
2005-08-12 16:22:33,863 DEBUG [org.hibernate.type.TimestampType] binding '2005-08-12 13:22:33' to parameter: 1
2005-08-12 16:22:33,863 DEBUG [org.hibernate.loader.hql.QueryLoader] bindNamedParameters() Fri Aug 12 13:22:33 GMT 2005 -> now [2]
2005-08-12 16:22:33,863 DEBUG [org.hibernate.type.TimestampType] binding '2005-08-12 13:22:33' to parameter: 2
2005-08-12 16:22:33,863 DEBUG [org.hibernate.loader.hql.QueryLoader] bindNamedParameters() Fri Aug 12 13:22:33 GMT 2005 -> now [3]
2005-08-12 16:22:33,863 DEBUG [org.hibernate.type.TimestampType] binding '2005-08-12 13:22:33' to parameter: 3
2005-08-12 16:22:33,863 DEBUG [org.hibernate.loader.hql.QueryLoader] bindNamedParameters() Fri Aug 12 13:22:33 GMT 2005 -> now [4]
2005-08-12 16:22:33,863 DEBUG [org.hibernate.type.TimestampType] binding '2005-08-12 13:22:33' to parameter: 4
2005-08-12 16:22:33,879 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] processing result set
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 0
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#1]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#1]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#1]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 1
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '3' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#3]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#3]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#3]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '21' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 2
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '4' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#4]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#4]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#4]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '22' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 3
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '5' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#5]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#5]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#5]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '23' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 4
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '6' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#6]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#6]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#6]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '24' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 5
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '7' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#7]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#7]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#7]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '25' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 6
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '8' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#8]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#8]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#8]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '26' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 7
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '9' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#9]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#9]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#9]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '27' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 8
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '10' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#10]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#10]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#10]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '28' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 9
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '11' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#11]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#11]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#11]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '29' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result set row: 10
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '12' as column: SUB1_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[Subscription#12]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] Initializing object from ResultSet: [Subscription#12]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.persister.entity.BasicEntityPersister] Hydrating entity: [Subscription#12]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '30' as column: SUB2_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning '1' as column: SUB3_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB4_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.TimestampType] returning null as column: SUB5_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.type.LongType] returning null as column: SUB6_20_
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] done processing result set (11 rows)
2005-08-12 16:22:33,879 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2005-08-12 16:22:33,879 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2005-08-12 16:22:33,879 DEBUG [org.hibernate.jdbc.AbstractBatcher] closing statement
2005-08-12 16:22:33,879 DEBUG [org.hibernate.loader.Loader] total objects hydrated: 11
2005-08-12 16:22:33,879 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#1]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#1]
2005-08-12 16:22:33,879 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#3]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#21]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#3]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#4]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#22]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#4]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#5]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#23]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#5]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#6]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#24]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#6]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#7]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#25]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#7]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#8]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#26]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#8]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#9]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#27]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#9]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#10]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#28]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#10]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#11]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#29]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#11]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [Subscription#12]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [LicensedUser#30]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] creating new proxy for entity
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] loading entity: [Service#1]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.event.def.DefaultLoadEventListener] entity proxy found in session cache
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [Subscription#12]
2005-08-12 16:22:33,895 DEBUG [org.hibernate.engine.PersistenceContext] initializing non-lazy collections