2nd Level Cached Query - 1st super slow, 2nd super fast

fischman_98 · **Joined:** Thu May 18, 2006 2:58 pm **Posts:** 28

I am querying a single table that has 17 columns (see below if necessary - table, POJO and mapping file). My test is retrieving 96 rows from the table using setFirstResult and setMaxResults and returning the query.list().

Code:

Query query = session.createQuery("from EPictureData").setCacheable(true);
query.setFirstResult(startRow);
query.setMaxResults(pageSize);
return query.list();

The first query takes 20 SECONDS. The second time I execute the query it takes 15ms. The output from the log (1st query)(log4j.logger.org.hibernate.SQL=debug) is:

Code:

11:55:45,250 DEBUG SQL:346 - select * from ( select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_ ) where rownum <= ?

Running the query in TOAD takes 15-31ms.

Code:

select * from ( select PICTURE_ID,SUBJECT,LOCATION,LEGEND,SUMMARY,EXPANDED_SUBJECT,MODEL_RELEASED,EXCLUSIVITY,TERRITORY_EXCLUSIVE,ICON_INDICATOR,   
CATEGORY,ORIENTATION,ASSET_TYPE,SUBMITTER_ID,SUBMITTER_NAME,COPYRIGHT_CODE,COPYRIGHT_DESC from E_PICTURE_DATA ) where rownum <= 96

When I turn the log JDBC bind parameters on (log4j.logger.org.hibernate.type=debug), I can watch the results on the console. They move along with no pauses, so the system appears is working, just going slow.

I am currently using Oracle 8 (on another box), Tomcat 5, MyFaces 1.1.3 and I am developing in XP Pro on a Dell WORKSTATION PWS360 with a Pentium 4 CPU 3.00 GHz, 2.99 GH, 1.00 GB RAM.

Is this what I should expect or is something slowing this query down?

How much will it improve when I move the application to a production server?

Code:

/* Auto generated file */

package org.hibernate.com.ngsimages.model;

import java.math.BigInteger;
import java.util.Calendar;
import java.util.Random;
import java.io.Serializable;

public class EPictureData implements Serializable {

   /** auto generated
    * @es_generated
    */
   private BigInteger pictureId;
   /** auto generated
    * @es_generated
    */
   private String subject;
   /** auto generated
    * @es_generated
    */
   private String location;
   /** auto generated
    * @es_generated
    */
   private String legend;
   /** auto generated
    * @es_generated
    */
   private String summary;
   /** auto generated
    * @es_generated
    */
   private String expandedSubject;
   /** auto generated
    * @es_generated
    */
   private Character modelReleased;
   /** auto generated
    * @es_generated
    */
   private Character exclusivity;
   /** auto generated
    * @es_generated
    */
   private Character territoryExclusive;
   /** auto generated
    * @es_generated
    */
   private Character iconIndicator;
   /** auto generated
    * @es_generated
    */
   private String category;
   /** auto generated
    * @es_generated
    */
   private String orientation;
   /** auto generated
    * @es_generated
    */
   private BigInteger assetType;
   /** auto generated
    * @es_generated
    */
   private BigInteger submitterId;
   /** auto generated
    * @es_generated
    */
   private String submitterName;
   /** auto generated
    * @es_generated
    */
   private String copyrightCode;
   /** auto generated
    * @es_generated
    */
   private String copyrightDesc;

   /** auto generated
    * @es_generated
    */
   public EPictureData() {
      super();
   }

   /** auto generated
    * @es_generated
    */
   public EPictureData(BigInteger pictureId, BigInteger assetType) {
      super();
      this.pictureId = pictureId;
      this.assetType = assetType;
   }

   /** auto generated
    * @es_generated
    */
   public EPictureData(BigInteger pictureId, String subject, String location,
         String legend, String summary, String expandedSubject,
         Character modelReleased, Character exclusivity,
         Character territoryExclusive, Character iconIndicator,
         String category, String orientation, BigInteger assetType,
         BigInteger submitterId, String submitterName, String copyrightCode,
         String copyrightDesc) {
      super();
      this.pictureId = pictureId;
      this.subject = subject;
      this.location = location;
      this.legend = legend;
      this.summary = summary;
      this.expandedSubject = expandedSubject;
      this.modelReleased = modelReleased;
      this.exclusivity = exclusivity;
      this.territoryExclusive = territoryExclusive;
      this.iconIndicator = iconIndicator;
      this.category = category;
      this.orientation = orientation;
      this.assetType = assetType;
      this.submitterId = submitterId;
      this.submitterName = submitterName;
      this.copyrightCode = copyrightCode;
      this.copyrightDesc = copyrightDesc;
   }

   /** auto generated
    * @es_generated
    */
   public boolean equals(Object value) {
      //TODO Implement equals() using Business key equality.   
      return super.equals(value);
   }

   /** auto generated
    * @es_generated
    */
   public int hashCode() {
      //TODO Implement hashCode() using Business key equality.   
      return super.hashCode();
   }

   /** auto generated
    * @es_generated
    */
   public String toString() {
      //TODO Implement toString().   
      return super.toString();
   }

   /** auto generated
    * @es_generated
    */
   public BigInteger getPictureId() {
      return this.pictureId;
   }

   /** auto generated
    * @es_generated
    */
   public void setPictureId(BigInteger value) {
      this.pictureId = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getSubject() {
      return this.subject;
   }

   /** auto generated
    * @es_generated
    */
   public void setSubject(String value) {
      this.subject = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getLocation() {
      return this.location;
   }

   /** auto generated
    * @es_generated
    */
   public void setLocation(String value) {
      this.location = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getLegend() {
      return this.legend;
   }

   /** auto generated
    * @es_generated
    */
   public void setLegend(String value) {
      this.legend = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getSummary() {
      return this.summary;
   }

   /** auto generated
    * @es_generated
    */
   public void setSummary(String value) {
      this.summary = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getExpandedSubject() {
      return this.expandedSubject;
   }

   /** auto generated
    * @es_generated
    */
   public void setExpandedSubject(String value) {
      this.expandedSubject = value;
   }

   /** auto generated
    * @es_generated
    */
   public Character getModelReleased() {
      return this.modelReleased;
   }

   /** auto generated
    * @es_generated
    */
   public void setModelReleased(Character value) {
      this.modelReleased = value;
   }

   /** auto generated
    * @es_generated
    */
   public Character getExclusivity() {
      return this.exclusivity;
   }

   /** auto generated
    * @es_generated
    */
   public void setExclusivity(Character value) {
      this.exclusivity = value;
   }

   /** auto generated
    * @es_generated
    */
   public Character getTerritoryExclusive() {
      return this.territoryExclusive;
   }

   /** auto generated
    * @es_generated
    */
   public void setTerritoryExclusive(Character value) {
      this.territoryExclusive = value;
   }

   /** auto generated
    * @es_generated
    */
   public Character getIconIndicator() {
      return this.iconIndicator;
   }

   /** auto generated
    * @es_generated
    */
   public void setIconIndicator(Character value) {
      this.iconIndicator = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getCategory() {
      return this.category;
   }

   /** auto generated
    * @es_generated
    */
   public void setCategory(String value) {
      this.category = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getOrientation() {
      return this.orientation;
   }

   /** auto generated
    * @es_generated
    */
   public void setOrientation(String value) {
      this.orientation = value;
   }

   /** auto generated
    * @es_generated
    */
   public BigInteger getAssetType() {
      return this.assetType;
   }

   /** auto generated
    * @es_generated
    */
   public void setAssetType(BigInteger value) {
      this.assetType = value;
   }

   /** auto generated
    * @es_generated
    */
   public BigInteger getSubmitterId() {
      return this.submitterId;
   }

   /** auto generated
    * @es_generated
    */
   public void setSubmitterId(BigInteger value) {
      this.submitterId = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getSubmitterName() {
      return this.submitterName;
   }

   /** auto generated
    * @es_generated
    */
   public void setSubmitterName(String value) {
      this.submitterName = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getCopyrightCode() {
      return this.copyrightCode;
   }

   /** auto generated
    * @es_generated
    */
   public void setCopyrightCode(String value) {
      this.copyrightCode = value;
   }

   /** auto generated
    * @es_generated
    */
   public String getCopyrightDesc() {
      return this.copyrightDesc;
   }

   /** auto generated
    * @es_generated
    */
   public void setCopyrightDesc(String value) {
      this.copyrightDesc = value;
   }
}

Code:

<?xml version="1.0" encoding="UTF-8"?>
<!DOCTYPE hibernate-mapping PUBLIC "-//Hibernate/Hibernate Mapping DTD 3.0//EN" "http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">

<hibernate-mapping schema="IRMAIR" package="org.hibernate.com.ngsimages.model"> 
  <class name="EPictureData" table="E_PICTURE_DATA" optimistic-lock="none"> 
    <cache usage="read-only" region="org.hibernate.com.ngsimages.model.EPictureData"/>
    <id name="pictureId" type="big_integer" unsaved-value="null">
      <column name="PICTURE_ID" not-null="true" precision="22" scale="0"/>
      <generator class="native"/>
    </id>
    <property name="subject" type="string" column="SUBJECT" length="4000"/>
    <property name="location" type="string" column="LOCATION" length="4000"/>
    <property name="legend" type="string" column="LEGEND" length="4000"/>
    <property name="summary" type="string" column="SUMMARY" length="4000"/>
    <property name="expandedSubject" type="text" column="EXPANDED_SUBJECT"></property>
    <property name="modelReleased" type="character" column="MODEL_RELEASED" length="1"/>
    <property name="exclusivity" type="character" column="EXCLUSIVITY" length="1"/>
    <property name="territoryExclusive" type="character" column="TERRITORY_EXCLUSIVE" length="1"/>
    <property name="iconIndicator" type="character" column="ICON_INDICATOR" length="1"/>
    <property name="category" type="string" column="CATEGORY" length="500"/>
    <property name="orientation" type="string" column="ORIENTATION" length="30"/>
    <property name="assetType" type="big_integer" column="ASSET_TYPE" precision="22" scale="0" not-null="true"></property>
    <property name="submitterId" type="big_integer" column="SUBMITTER_ID" precision="22" scale="0"></property>
    <property name="submitterName" type="string" column="SUBMITTER_NAME" length="200"/>
    <property name="copyrightCode" type="string" column="COPYRIGHT_CODE" length="3"/>
    <property name="copyrightDesc" type="string" column="COPYRIGHT_DESC" length="2000"/>
  </class>
</hibernate-mapping>

Code:

COLUMN_NAME   NULLABLE   DATA_TYPE   DATA_LENGTH   DATA_PRECISION   DATA_SCALE
PICTURE_ID   N   NUMBER            
SUBJECT   Y   VARCHAR2   4000         
LOCATION   Y   VARCHAR2   4000         
LEGEND   Y   VARCHAR2   4000         
SUMMARY   Y   VARCHAR2   4000         
EXPANDED_SUBJECT   Y   CLOB   4000         
MODEL_RELEASED   Y   CHAR   1         
EXCLUSIVITY   Y   CHAR   1         
TERRITORY_EXCLUSIVE   Y   CHAR   1         
ICON_INDICATOR   Y   CHAR   1         
CATEGORY   Y   VARCHAR2   500         
ORIENTATION   Y   VARCHAR2   30         
ASSET_TYPE   N   NUMBER            
SUBMITTER_ID   Y   NUMBER            
SUBMITTER_NAME   Y   VARCHAR2   200         
COPYRIGHT_CODE   Y   VARCHAR2   3         
COPYRIGHT_DESC   Y   VARCHAR2   2000

jherbst · **Posted:** Sat Jul 29, 2006 6:59 am

No it isn't. The query should be no longer, execute from Hibernate or from any other JDBC based program.
The long time (20 seconds) may come from the first connection to you're database? Can you post some more log lines? What takes 20 seconds, running the SQL statement or transforming the resultset into objects?

damnhandy · **Posted:** Sun Jul 30, 2006 5:06 pm

Your first execution is unsually long. However, when an L2 cache is involved, the first call to the query will be slower than subsequent calls. This is due to that fact that it all 96 elements are being added to the cache as well as the query. The next time the exact same query (parameters and all), there will be no database hit.

You may also want to provide your cache configuration for both your hibernate.cfg.xml and what ever cache provider your are using. 20 seconds to populate the cache is slow.

Ryan-

fischman_98 · **Joined:** Thu May 18, 2006 2:58 pm **Posts:** 28

Thanks for the thoughts...below I posted the console output...please notice the timestamps to view the duration...

Any thoughts!?!?

Thanks.

Matt

[code]14:46:57,366 DEBUG SessionImpl:219 - opened session at timestamp: 4728306144731136
14:46:57,366 DEBUG ThreadLocalSessionContext:290 - allowing method [beginTransaction] in non-transacted context
14:46:57,366 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [beginTransaction] to proceed to real session
14:46:57,366 DEBUG JDBCTransaction:54 - begin
14:46:57,366 DEBUG ConnectionManager:415 - opening JDBC connection
14:46:57,366 DEBUG JDBCTransaction:59 - current autocommit status: true
14:46:57,366 DEBUG JDBCTransaction:62 - disabling autocommit
14:46:57,366 DEBUG JDBCContext:194 - after transaction begin
am i here-->
14:46:57,444 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [beginTransaction] to proceed to real session
14:46:57,460 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [createQuery] to proceed to real session
14:46:57,460 DEBUG QueryPlanCache:69 - unable to locate HQL query plan in cache; generating (select count(*) from EPictureData)
14:46:57,601 DEBUG QueryTranslatorImpl:236 - parse() - HQL: select count(*) from org.hibernate.com.ngsimages.model.EPictureData
14:46:57,616 DEBUG AST:252 - --- HQL AST ---
\-[QUERY] 'query'
\-[SELECT_FROM] 'SELECT_FROM'
+-[FROM] 'from'
| \-[RANGE] 'RANGE'
| \-[DOT] '.'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[DOT] '.'
| | | | +-[DOT] '.'
| | | | | +-[IDENT] 'org'
| | | | | \-[IDENT] 'hibernate'
| | | | \-[IDENT] 'com'
| | | \-[IDENT] 'ngsimages'
| | \-[IDENT] 'model'
| \-[IDENT] 'EPictureData'
\-[SELECT] 'select'
\-[COUNT] 'count'
\-[ROW_STAR] '*'

14:46:57,616 DEBUG ErrorCounter:68 - throwQueryException() : no errors
14:46:57,726 DEBUG HqlSqlBaseWalker:111 - select << begin [level=1, statement=select]
14:46:57,757 DEBUG FromElement:104 - FromClause{level=1} : org.hibernate.com.ngsimages.model.EPictureData (no alias) -> epictureda0_
14:46:57,773 DEBUG HqlSqlBaseWalker:117 - select : finishing up [level=1, statement=select]
14:46:57,773 DEBUG HqlSqlWalker:511 - processQuery() : ( SELECT ( {select clause} ( count * ) ) ( FromClause{level=1} IRMAIR.E_PICTURE_DATA epictureda0_ ) )
14:46:57,788 DEBUG JoinProcessor:128 - Using FROM fragment [IRMAIR.E_PICTURE_DATA epictureda0_]
14:46:57,788 DEBUG HqlSqlBaseWalker:123 - select >> end [level=1, statement=select]
14:46:57,804 DEBUG AST:222 - --- SQL AST ---
\-[SELECT] QueryNode: 'SELECT' querySpaces (IRMAIR.E_PICTURE_DATA)
+-[SELECT_CLAUSE] SelectClause: '{select clause}'
| +-[COUNT] CountNode: 'count'
| | \-[ROW_STAR] SqlNode: '*'
| \-[SELECT_COLUMNS] SqlNode: ' as col_0_0_'
\-[FROM] FromClause: 'from' FromClause{level=1, fromElementCounter=1, fromElements=1, fromElementByClassAlias=[], fromElementByTableAlias=[epictureda0_], fromElementsByPath=[], collectionJoinFromElementsByPath=[], impliedElements=[]}
\-[FROM_FRAGMENT] FromElement: 'IRMAIR.E_PICTURE_DATA epictureda0_' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=IRMAIR.E_PICTURE_DATA,tableAlias=epictureda0_,origin=null,colums={,className=org.hibernate.com.ngsimages.model.EPictureData}}

14:46:57,804 DEBUG ErrorCounter:68 - throwQueryException() : no errors
14:46:57,820 DEBUG QueryTranslatorImpl:206 - HQL: select count(*) from org.hibernate.com.ngsimages.model.EPictureData
14:46:57,820 DEBUG QueryTranslatorImpl:207 - SQL: select count(*) as col_0_0_ from IRMAIR.E_PICTURE_DATA epictureda0_
14:46:57,820 DEBUG ErrorCounter:68 - throwQueryException() : no errors
14:46:57,851 DEBUG HQLQueryPlan:219 - HQL param location recognition took 16 mills (select count(*) from EPictureData)
14:46:57,851 DEBUG QueryPlanCache:75 - located HQL query plan in cache (select count(*) from EPictureData)
14:46:57,851 DEBUG HQLQueryPlan:148 - find: select count(*) from EPictureData
14:46:57,866 DEBUG QueryParameters:262 - named parameters: {}
14:46:57,866 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
14:46:57,866 DEBUG SQL:346 - select count(*) as col_0_0_ from IRMAIR.E_PICTURE_DATA epictureda0_
Hibernate: select count(*) as col_0_0_ from IRMAIR.E_PICTURE_DATA epictureda0_
14:46:57,866 DEBUG AbstractBatcher:424 - preparing statement
14:46:57,945 DEBUG AbstractBatcher:327 - about to open ResultSet (open ResultSets: 0, globally: 0)
14:46:57,945 DEBUG Loader:682 - processing result set
14:46:57,945 DEBUG Loader:687 - result set row: 0
14:46:57,945 DEBUG Loader:1164 - result row:
14:46:57,960 DEBUG IntegerType:122 - returning '15317' as column: col_0_0_
14:46:57,960 DEBUG Loader:709 - done processing result set (1 rows)
14:46:57,960 DEBUG AbstractBatcher:334 - about to close ResultSet (open ResultSets: 1, globally: 1)
14:46:57,960 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
14:46:57,960 DEBUG AbstractBatcher:470 - closing statement
14:46:57,976 DEBUG StatefulPersistenceContext:748 - initializing non-lazy collections
getColumns -> 1154371618320 ms.
getDataModel -> 1154371618335 ms.
startRow-->0 pageSize-->32
14:46:58,335 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [beginTransaction] to proceed to real session
14:46:58,335 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [createQuery] to proceed to real session
14:46:58,335 DEBUG QueryPlanCache:69 - unable to locate HQL query plan in cache; generating (from EPictureData)
14:46:58,335 DEBUG QueryTranslatorImpl:236 - parse() - HQL: from org.hibernate.com.ngsimages.model.EPictureData
14:46:58,335 DEBUG AST:252 - --- HQL AST ---
\-[QUERY] 'query'
\-[SELECT_FROM] 'SELECT_FROM'
\-[FROM] 'from'
\-[RANGE] 'RANGE'
\-[DOT] '.'
+-[DOT] '.'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[DOT] '.'
| | | | +-[IDENT] 'org'
| | | | \-[IDENT] 'hibernate'
| | | \-[IDENT] 'com'
| | \-[IDENT] 'ngsimages'
| \-[IDENT] 'model'
\-[IDENT] 'EPictureData'

14:46:58,335 DEBUG ErrorCounter:68 - throwQueryException() : no errors
14:46:58,351 DEBUG HqlSqlBaseWalker:111 - select << begin [level=1, statement=select]
14:46:58,351 DEBUG FromElement:104 - FromClause{level=1} : org.hibernate.com.ngsimages.model.EPictureData (no alias) -> epictureda0_
14:46:58,351 DEBUG HqlSqlBaseWalker:117 - select : finishing up [level=1, statement=select]
14:46:58,351 DEBUG HqlSqlWalker:511 - processQuery() : ( SELECT ( FromClause{level=1} IRMAIR.E_PICTURE_DATA epictureda0_ ) )
14:46:58,351 DEBUG HqlSqlWalker:713 - Derived SELECT clause created.
14:46:58,351 DEBUG JoinProcessor:128 - Using FROM fragment [IRMAIR.E_PICTURE_DATA epictureda0_]
14:46:58,366 DEBUG HqlSqlBaseWalker:123 - select >> end [level=1, statement=select]
14:46:58,366 DEBUG AST:222 - --- SQL AST ---
\-[SELECT] QueryNode: 'SELECT' querySpaces (IRMAIR.E_PICTURE_DATA)
+-[SELECT_CLAUSE] SelectClause: '{derived select clause}'
| +-[SELECT_EXPR] SelectExpressionImpl: 'epictureda0_.PICTURE_ID as PICTURE1_1_' {FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=IRMAIR.E_PICTURE_DATA,tableAlias=epictureda0_,origin=null,colums={,className=org.hibernate.com.ngsimages.model.EPictureData}}}
| \-[SQL_TOKEN] SqlFragment: 'epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_'
\-[FROM] FromClause: 'from' FromClause{level=1, fromElementCounter=1, fromElements=1, fromElementByClassAlias=[], fromElementByTableAlias=[epictureda0_], fromElementsByPath=[], collectionJoinFromElementsByPath=[], impliedElements=[]}
\-[FROM_FRAGMENT] FromElement: 'IRMAIR.E_PICTURE_DATA epictureda0_' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=IRMAIR.E_PICTURE_DATA,tableAlias=epictureda0_,origin=null,colums={,className=org.hibernate.com.ngsimages.model.EPictureData}}

14:46:58,366 DEBUG ErrorCounter:68 - throwQueryException() : no errors
14:46:58,366 DEBUG QueryTranslatorImpl:206 - HQL: from org.hibernate.com.ngsimages.model.EPictureData
14:46:58,366 DEBUG QueryTranslatorImpl:207 - SQL: select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_
14:46:58,366 DEBUG ErrorCounter:68 - throwQueryException() : no errors
14:46:58,366 DEBUG HQLQueryPlan:219 - HQL param location recognition took 0 mills (from EPictureData)
time -> 31 ms.
14:46:58,366 DEBUG QueryPlanCache:75 - located HQL query plan in cache (from EPictureData)
14:46:58,382 DEBUG HQLQueryPlan:148 - find: from EPictureData
14:46:58,382 DEBUG QueryParameters:262 - named parameters: {}
14:46:58,382 DEBUG StandardQueryCache:93 - checking cached query results in region: org.hibernate.cache.StandardQueryCache
14:46:58,382 DEBUG EhCache:104 - key: sql: select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_; parameters: ; named parameters: {}; first row: 0; max rows: 96
14:46:58,382 DEBUG EhCache:113 - Element for sql: select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_; parameters: ; named parameters: {}; first row: 0; max rows: 96 is null
14:46:58,382 DEBUG StandardQueryCache:98 - query results were not found in cache
14:46:58,382 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
14:46:58,382 DEBUG SQL:346 - select * from ( select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_ ) where rownum <= ?
Hibernate: select * from ( select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_ ) where rownum <= ?
14:46:58,382 DEBUG AbstractBatcher:424 - preparing statement
14:46:58,398 DEBUG AbstractBatcher:327 - about to open ResultSet (open ResultSets: 0, globally: 0)
14:46:58,398 DEBUG Loader:682 - processing result set
14:46:58,398 DEBUG Loader:687 - result set row: 0
14:46:58,413 DEBUG BigIntegerType:122 - returning '1488' as column: PICTURE1_1_
14:46:58,413 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#1488]
14:46:58,413 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#1488]
14:46:58,429 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#1488]
14:46:58,429 DEBUG StringType:122 - returning 'MINGAN ARCHIPELAGO NATIONAL PARK PRESERVE;REFUGES AND RESERVES;NIAPISKAU ISLAND;BOARDWALKS AND PROMENADES;HIKERS AND HIKING;BAYS AND INLETS;SAINT LAWRENCE RIVER (QUEBEC);EVERGREEN TREES;SCENIC VIEWS;LIMESTONE;ROCK FORMATIONS;LICHENS;FORMAL GARDENS;PATHS' as column: SUBJECT1_
14:46:58,429 DEBUG StringType:122 - returning 'Mingan Archipelago National Park Reserve, Niapiskau Island, Mingan Islands, St. Lawrence River, Off The Coast Of Quebec, Canada.' as column: LOCATION1_
14:46:58,429 DEBUG StringType:122 - returning 'A Hiker Walks Along A Boardwalk On Niapiskau Island.' as column: LEGEND1_
14:46:58,445 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:46:58,616 DEBUG TextType:122 - returning 'NORTH AMERICA;CANADA;QUEBEC;NATIONAL PARKS (QUEBEC);MINGAN ARCHIPELAGO NATIONAL PARK PRESERVE;REFUGES AND RESERVES;MINGAN ISLANDS;NIAPISKAU ISLAND;WALKWAYS;BOARDWALKS AND PROMENADES;HIKERS AND HIKING;GEOGRAPHY;BAYS AND INLETS;SAINT LAWRENCE RIVER (QUEBEC);PLANTS;TREES;EVERGREEN TREES;SCENES AND VIEWS;SCENIC VIEWS;ROCK;LIMESTONE;ROCK FORMATIONS;LICHENS;GARDENS;FORMAL GARDENS;PATHS;' as column: EXPANDED6_1_
14:46:58,616 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:46:58,616 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:46:58,616 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:46:58,616 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:46:58,616 DEBUG StringType:122 - returning 'TRAVEL' as column: CATEGORY1_
14:46:58,616 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
14:46:58,632 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:46:58,632 DEBUG BigIntegerType:122 - returning '696570' as column: SUBMITTER14_1_
14:46:58,632 DEBUG StringType:122 - returning 'Michael Melford' as column: SUBMITTER15_1_
14:46:58,632 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
14:46:58,632 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:46:58,632 DEBUG Loader:687 - result set row: 1
14:46:58,632 DEBUG BigIntegerType:122 - returning '1549' as column: PICTURE1_1_
14:46:58,632 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#1549]
14:46:58,632 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#1549]
14:46:58,648 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#1549]
14:46:58,648 DEBUG StringType:122 - returning 'TREE ROOTS;RED MANGROVE TREES;RED MANGROVE SHRUBS;RHIZOPHORA MANGLE;ADAPTATION (BY PLANTS);REFUGES AND RESERVES;BISCAYNE BAY;BISCAYNE NATIONAL PARK;BAYS AND INLETS' as column: SUBJECT1_
14:46:58,648 DEBUG StringType:122 - returning 'Biscayne National Park, Biscayne Bay, Southern Florida.' as column: LOCATION1_
14:46:58,648 DEBUG StringType:122 - returning 'Root Legs Of Red Mangroves Extend Into Biscayne Bay. ' as column: LEGEND1_
14:46:58,648 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:46:58,804 DEBUG TextType:122 - returning 'PLANT PHYSIOLOGY;PLANT GROWTH AND DECAY;PLANT ROOTS;TREE ROOTS;PLANTS;TREES;MANGROVE TREES;RED MANGROVE TREES;SHRUBS;MANGROVE SHRUBS;RED MANGROVE SHRUBS;RHIZOPHORA MANGLE;ADAPTATION (BY PLANTS);REFUGES AND RESERVES;ATLANTIC OCEAN;BISCAYNE BAY;NORTH AMERICA;UNITED STATES;SOUTHERN STATES;FLORIDA;NATIONAL PARKS (FLORIDA);BISCAYNE NATIONAL PARK;GEOGRAPHY;BAYS AND INLETS;' as column: EXPANDED6_1_
14:46:58,804 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:46:58,804 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:46:58,820 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:46:58,820 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:46:58,820 DEBUG StringType:122 - returning 'NATURAL HISTORY' as column: CATEGORY1_
14:46:58,820 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
14:46:58,820 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:46:58,820 DEBUG BigIntegerType:122 - returning '401005' as column: SUBMITTER14_1_
14:46:58,820 DEBUG StringType:122 - returning 'Medford Taylor' as column: SUBMITTER15_1_
14:46:58,820 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
14:46:58,835 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:46:58,835 DEBUG Loader:687 - result set row: 2
14:46:58,835 DEBUG BigIntegerType:122 - returning '2630' as column: PICTURE1_1_
14:46:58,835 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2630]
14:46:58,835 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2630]
14:46:58,835 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2630]
14:46:58,835 DEBUG StringType:122 - returning 'MISSOURI RIVER (MONTANA);WHITE CLIFFS (MONTANA);AERIAL VIEWS;LANDSCAPES;RIVERS;TWILIGHT VIEWS' as column: SUBJECT1_
14:46:58,835 DEBUG StringType:122 - returning 'White Cliffs Region, Missouri River, Montana.' as column: LOCATION1_
14:46:58,835 DEBUG StringType:122 - returning 'Aerial Of The Missouri River In Montana'S White Cliffs Region.' as column: LEGEND1_
14:46:58,835 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:46:59,007 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;MONTANA;MISSOURI RIVER (MONTANA);WHITE CLIFFS (MONTANA);SCENES AND VIEWS;AERIAL VIEWS;LANDSCAPES;GEOGRAPHY;RIVERS;TWILIGHT VIEWS;' as column: EXPANDED6_1_
14:46:59,007 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:46:59,007 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:46:59,007 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:46:59,007 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:46:59,023 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
14:46:59,023 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:46:59,023 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:46:59,023 DEBUG BigIntegerType:122 - returning '403879' as column: SUBMITTER14_1_
14:46:59,023 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:46:59,023 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:46:59,023 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:46:59,038 DEBUG Loader:687 - result set row: 3
14:46:59,038 DEBUG BigIntegerType:122 - returning '2631' as column: PICTURE1_1_
14:46:59,038 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2631]
14:46:59,038 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2631]
14:46:59,038 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2631]
14:46:59,038 DEBUG StringType:122 - returning 'COLUMBIA RIVER (WASHINGTON);SUN;CLOUDS;TWILIGHT VIEWS;RIVERS' as column: SUBJECT1_
14:46:59,038 DEBUG StringType:122 - returning 'Columbia River, Washington.' as column: LOCATION1_
14:46:59,038 DEBUG StringType:122 - returning 'Early Morning Sun And Clouds Over The Mouth Of The Columbia River.' as column: LEGEND1_
14:46:59,038 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:46:59,210 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;PACIFIC COAST STATES;WASHINGTON;COLUMBIA RIVER (WASHINGTON);SUN;CLOUDS;SCENES AND VIEWS;TWILIGHT VIEWS;GEOGRAPHY;RIVERS;' as column: EXPANDED6_1_
14:46:59,210 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:46:59,210 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:46:59,210 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:46:59,210 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:46:59,210 DEBUG StringType:122 - returning 'NATURAL HISTORY' as column: CATEGORY1_
14:46:59,210 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:46:59,226 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:46:59,226 DEBUG BigIntegerType:122 - returning '752907' as column: SUBMITTER14_1_
14:46:59,226 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:46:59,226 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:46:59,226 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:46:59,226 DEBUG Loader:687 - result set row: 4
14:46:59,226 DEBUG BigIntegerType:122 - returning '2633' as column: PICTURE1_1_
14:46:59,226 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2633]
14:46:59,226 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2633]
14:46:59,226 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2633]
14:46:59,242 DEBUG StringType:122 - returning 'OREGON;PACIFIC OCEAN;TWILIGHT VIEWS;CLOUDS;SCENIC VIEWS;BEACHES' as column: SUBJECT1_
14:46:59,242 DEBUG StringType:122 - returning 'Pacific Ocean, Oregon Coast.' as column: LOCATION1_
14:46:59,242 DEBUG StringType:122 - returning 'Twilight View Of Pacific Ocean From Oregon Coast.' as column: LEGEND1_
14:46:59,242 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:46:59,413 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;PACIFIC COAST STATES;OREGON;PACIFIC OCEAN;SCENES AND VIEWS;TWILIGHT VIEWS;CLOUDS;SCENIC VIEWS;GEOGRAPHY;BEACHES;' as column: EXPANDED6_1_
14:46:59,413 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:46:59,413 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:46:59,413 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:46:59,413 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:46:59,413 DEBUG StringType:122 - returning 'NATURAL HISTORY' as column: CATEGORY1_
14:46:59,413 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:46:59,413 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:46:59,429 DEBUG BigIntegerType:122 - returning '695140' as column: SUBMITTER14_1_
14:46:59,429 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:46:59,429 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:46:59,429 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:46:59,429 DEBUG Loader:687 - result set row: 5
14:46:59,429 DEBUG BigIntegerType:122 - returning '2637' as column: PICTURE1_1_
14:46:59,429 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2637]
14:46:59,429 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2637]
14:46:59,429 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2637]
14:46:59,429 DEBUG StringType:122 - returning 'LEMHI PASS (MONTANA);PASSES;GRASSES' as column: SUBJECT1_
14:46:59,429 DEBUG StringType:122 - returning 'Lemhi Pass, Montana.' as column: LOCATION1_
14:46:59,445 DEBUG StringType:122 - returning 'Grassy Lemhi Pass.' as column: LEGEND1_
14:46:59,445 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:46:59,617 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;MONTANA;ROCKY MOUNTAINS (MONTANA);BITTERROOT RANGE (MONTANA);LEMHI PASS (MONTANA);PASSES;PLANTS;GRASSES;' as column: EXPANDED6_1_
14:46:59,617 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:46:59,617 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:46:59,617 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:46:59,617 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:46:59,617 DEBUG StringType:122 - returning 'NATURAL' as column: CATEGORY1_
14:46:59,617 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:46:59,617 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:46:59,617 DEBUG BigIntegerType:122 - returning '400134' as column: SUBMITTER14_1_
14:46:59,617 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:46:59,632 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:46:59,632 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:46:59,632 DEBUG Loader:687 - result set row: 6
14:46:59,632 DEBUG BigIntegerType:122 - returning '2639' as column: PICTURE1_1_
14:46:59,632 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2639]
14:46:59,632 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2639]
14:46:59,632 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2639]
14:46:59,632 DEBUG StringType:122 - returning 'BITTERROOT RANGE (MONTANA);MOUNTAINS;FLOWERING FIELDS (WILDFLOWERS);WILDFLOWERS;MEADOWS;SCENIC VIEWS;LANDSCAPES' as column: SUBJECT1_
14:46:59,632 DEBUG StringType:122 - returning 'Bitterroot Range, Montana.' as column: LOCATION1_
14:46:59,632 DEBUG StringType:122 - returning 'View From Wildflower Meadow Of The Rockies' Bitterroot Range.' as column: LEGEND1_
14:46:59,632 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:46:59,820 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;MONTANA;ROCKY MOUNTAINS (MONTANA);BITTERROOT RANGE (MONTANA);GEOGRAPHY;MOUNTAINS;FIELDS;FLOWERING FIELDS (WILDFLOWERS);PLANTS;FLOWERS;WILDFLOWERS;GRASSLANDS;MEADOWS;SCENES AND VIEWS;SCENIC VIEWS;LANDSCAPES;' as column: EXPANDED6_1_
14:46:59,820 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:46:59,820 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:46:59,820 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:46:59,820 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:46:59,820 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
14:46:59,820 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:46:59,820 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:46:59,820 DEBUG BigIntegerType:122 - returning '699225' as column: SUBMITTER14_1_
14:46:59,820 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:46:59,820 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:46:59,835 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:46:59,835 DEBUG Loader:687 - result set row: 7
14:46:59,835 DEBUG BigIntegerType:122 - returning '2642' as column: PICTURE1_1_
14:46:59,835 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2642]
14:46:59,835 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2642]
14:46:59,835 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2642]
14:46:59,835 DEBUG StringType:122 - returning 'WEIPPE PRAIRIE;EVERGREEN TREES;PRAIRIES' as column: SUBJECT1_
14:46:59,835 DEBUG StringType:122 - returning 'Weippe Prairie, Idaho.' as column: LOCATION1_
14:46:59,835 DEBUG StringType:122 - returning 'A Lone Evergreen Tree Stands Tall On The Weippe Prairie.' as column: LEGEND1_
14:46:59,835 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:00,007 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;IDAHO;WEIPPE PRAIRIE;PLANTS;TREES;EVERGREEN TREES;GEOGRAPHY;GRASSLANDS;PRAIRIES;' as column: EXPANDED6_1_
14:47:00,007 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:00,023 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:00,023 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:00,023 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:00,023 DEBUG StringType:122 - returning 'CONCEPTS,NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
14:47:00,023 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:47:00,023 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:00,023 DEBUG BigIntegerType:122 - returning '699225' as column: SUBMITTER14_1_
14:47:00,023 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:47:00,023 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:47:00,023 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:00,039 DEBUG Loader:687 - result set row: 8
14:47:00,039 DEBUG BigIntegerType:122 - returning '2644' as column: PICTURE1_1_
14:47:00,039 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2644]
14:47:00,039 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2644]
14:47:00,039 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2644]
14:47:00,039 DEBUG StringType:122 - returning 'COLUMBIA RIVER (WASHINGTON);CONFLUENCES;SKY;TWILIGHT VIEWS' as column: SUBJECT1_
14:47:00,039 DEBUG StringType:122 - returning 'Near The Confluence Of The Snake And Columbia Rivers, Washington.' as column: LOCATION1_
14:47:00,039 DEBUG StringType:122 - returning 'Twilight Sky Over The Columbia River.' as column: LEGEND1_
14:47:00,039 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:00,210 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;PACIFIC COAST STATES;WASHINGTON;COLUMBIA RIVER (WASHINGTON);GEOGRAPHY;CONFLUENCES;SKY;SCENES AND VIEWS;TWILIGHT VIEWS;' as column: EXPANDED6_1_
14:47:00,210 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:00,210 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:00,210 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:00,226 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:00,226 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
14:47:00,226 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:47:00,226 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:00,226 DEBUG BigIntegerType:122 - returning '957504' as column: SUBMITTER14_1_
14:47:00,226 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:47:00,226 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:47:00,226 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:00,226 DEBUG Loader:687 - result set row: 9
14:47:00,242 DEBUG BigIntegerType:122 - returning '2645' as column: PICTURE1_1_
14:47:00,242 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2645]
14:47:00,242 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2645]
14:47:00,242 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2645]
14:47:00,242 DEBUG StringType:122 - returning 'TWO SISTERS (IDAHO);BUTTES;ROCK FORMATIONS' as column: SUBJECT1_
14:47:00,242 DEBUG StringType:122 - returning 'Two Sisters, Idaho.' as column: LOCATION1_
14:47:00,242 DEBUG StringType:122 - returning 'Two Sisters Buttes Landmark.' as column: LEGEND1_
14:47:00,242 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:00,414 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;IDAHO;TWO SISTERS (IDAHO);GEOGRAPHY;HILLS;BUTTES;ROCK FORMATIONS;' as column: EXPANDED6_1_
14:47:00,414 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:00,414 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:00,414 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:00,429 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:00,429 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
14:47:00,429 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:47:00,429 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:00,429 DEBUG BigIntegerType:122 - returning '695140' as column: SUBMITTER14_1_
14:47:00,429 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:47:00,429 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:47:00,429 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:00,429 DEBUG Loader:687 - result set row: 10
14:47:00,445 DEBUG BigIntegerType:122 - returning '2653' as column: PICTURE1_1_
14:47:00,445 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2653]
14:47:00,445 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2653]
14:47:00,445 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2653]
14:47:00,445 DEBUG StringType:122 - returning 'SQUARE BUTTE;BUTTES;SCENIC VIEWS;THE MOON;TWILIGHT VIEWS;LANDSCAPES;DESOLATE LANDSCAPES' as column: SUBJECT1_
14:47:00,445 DEBUG StringType:122 - returning 'Square Butte, Montana.' as column: LOCATION1_
14:47:00,445 DEBUG StringType:122 - returning 'Moonlight Gilds Montana'S Square Butte.' as column: LEGEND1_
14:47:00,445 DEBUG StringType:122 - returning 'This Landmark Guided Lewis Toward The Rockies On His Way West And On His Way Home As He Left The Mountains.' as column: SUMMARY1_
14:47:00,617 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;MONTANA;SQUARE BUTTE;GEOGRAPHY;HILLS;BUTTES;SCENES AND VIEWS;SCENIC VIEWS;MOONS;THE MOON;TWILIGHT VIEWS;LANDSCAPES;DESOLATE LANDSCAPES;' as column: EXPANDED6_1_
14:47:00,617 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:00,617 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:00,617 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:00,617 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:00,617 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
14:47:00,617 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:47:00,632 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:00,632 DEBUG BigIntegerType:122 - returning '406543' as column: SUBMITTER14_1_
14:47:00,632 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:47:00,632 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:47:00,632 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:00,632 DEBUG Loader:687 - result set row: 11
14:47:00,632 DEBUG BigIntegerType:122 - returning '4128' as column: PICTURE1_1_
14:47:00,632 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#4128]
14:47:00,632 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#4128]
14:47:00,648 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#4128]
14:47:00,648 DEBUG StringType:122 - returning 'ENGLAND;STRATFORD UPON AVON;SHAKESPEAREAN GARDENS;DOMESTIC SHORTHAIR CATS;SHAKESPEARE, WILLIAM;HERBS AND SPICES' as column: SUBJECT1_
14:47:00,648 DEBUG StringType:122 - returning 'Stratford-Upon-Avon, England.' as column: LOCATION1_
14:47:00,648 DEBUG StringType:122 - returning 'White Cat Perched On A Fence Overlooking The Gardens At Stratford-Upon-Avon.' as column: LEGEND1_
14:47:00,648 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:00,820 DEBUG TextType:122 - returning 'EUROPE;GREAT BRITAIN;ENGLAND;WARWICKSHIRE (COUNTY IN ENGLAND);STRATFORD UPON AVON;NORTH AMERICA;CANADA;ONTARIO;SHAKESPEAREAN GARDENS;ANIMALS;MAMMALS;FELINES;DOMESTIC CATS;DOMESTIC SHORTHAIR CATS;SHAKESPEARE, WILLIAM;HERBS AND SPICES;' as column: EXPANDED6_1_
14:47:00,820 DEBUG CharacterType:116 - returning null as column: MODEL7_1_
14:47:00,820 DEBUG CharacterType:116 - returning null as column: EXCLUSIV8_1_
14:47:00,820 DEBUG CharacterType:116 - returning null as column: TERRITORY9_1_
14:47:00,820 DEBUG CharacterType:116 - returning null as column: ICON10_1_
14:47:00,820 DEBUG StringType:116 - returning null as column: CATEGORY1_
14:47:00,820 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
14:47:00,835 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:00,835 DEBUG BigIntegerType:122 - returning '91627' as column: SUBMITTER14_1_
14:47:00,835 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
14:47:00,835 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:47:00,835 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:00,835 DEBUG Loader:687 - result set row: 12
14:47:00,835 DEBUG BigIntegerType:122 - returning '7269' as column: PICTURE1_1_
14:47:00,835 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#7269]
14:47:00,835 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#7269]
14:47:00,835 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#7269]
14:47:00,851 DEBUG StringType:122 - returning 'HUMAN BONES AND SKELETONS;NATIONAL GEOGRAPHIC SOCIETY RESEARCH GRANTS;CLOSE VIEWS;VOLCANIC ERUPTIONS;SKULLS;HERCULANEUM' as column: SUBJECT1_
14:47:00,851 DEBUG StringType:122 - returning 'Herculaneum, Italy.' as column: LOCATION1_
14:47:00,851 DEBUG StringType:122 - returning 'A Close View Of Embracing Skeletons Excavated On Herculaneum'S Beach.' as column: LEGEND1_
14:47:00,851 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:01,023 DEBUG TextType:122 - returning 'BONES AND SKELETONS;HUMAN BONES AND SKELETONS;NATIONAL GEOGRAPHIC SOCIETY;NATIONAL GEOGRAPHIC SOCIETY ACTIVITIES;NATIONAL GEOGRAPHIC SOCIETY RESEARCH GRANTS;PHOTOGRAPHY;CLOSE VIEWS;NATURAL FORCES AND PHENOMENA;VOLCANIC ERUPTIONS;SKULLS;EUROPE;ITALY;CAMPANIA REGION;HERCULANEUM;' as column: EXPANDED6_1_
14:47:01,023 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:01,023 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:01,023 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:01,023 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:01,023 DEBUG StringType:122 - returning 'PEOPLE,WORLD CULTURES' as column: CATEGORY1_
14:47:01,023 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
14:47:01,023 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:01,039 DEBUG BigIntegerType:122 - returning '406543' as column: SUBMITTER14_1_
14:47:01,039 DEBUG StringType:122 - returning 'O. Louis Mazzatenta' as column: SUBMITTER15_1_
14:47:01,039 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:47:01,039 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:01,039 DEBUG Loader:687 - result set row: 13
14:47:01,039 DEBUG BigIntegerType:122 - returning '8268' as column: PICTURE1_1_
14:47:01,039 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#8268]
14:47:01,039 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#8268]
14:47:01,039 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#8268]
14:47:01,039 DEBUG StringType:122 - returning 'HYDE PARK (ENGLAND);SHADOWS;BENCHES AND CHAIRS;OUTDOOR FURNISHINGS;SUNBATHING (BY HUMANS);NATIONAL PRESS PHOTOGRAPHERS ASSOCIATION CONTEST 1984;WHITE HOUSE PHOTOGRAPHERS ASSOCIATION CONTEST 1985;REAR VIEWS' as column: SUBJECT1_
14:47:01,039 DEBUG StringType:122 - returning 'Hyde Park, London, England.' as column: LOCATION1_
14:47:01,054 DEBUG StringType:122 - returning 'Silhouette Of A Man Sitting In A Folding Chair On The The Serpentine.' as column: LEGEND1_
14:47:01,054 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:01,226 DEBUG TextType:122 - returning 'EUROPE;GREAT BRITAIN;ENGLAND;GREATER LONDON (COUNTY IN ENGLAND);LONDON (ENGLAND);HYDE PARK (ENGLAND);SHADOWS;FURNISHINGS;BENCHES AND CHAIRS;OUTDOOR FURNISHINGS;HUMAN ACTIONS AND REACTIONS;SUNBATHING (BY HUMANS);NATIONAL PRESS PHOTOGRAPHERS ASSOCIATION CONTEST 1984;WHITE HOUSE PHOTOGRAPHERS ASSOCIATION CONTEST 1985;SCENES AND VIEWS;REAR VIEWS;' as column: EXPANDED6_1_
14:47:01,226 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:01,226 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:01,226 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:01,226 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:01,226 DEBUG StringType:122 - returning 'PEOPLE,WORLD CULTURES' as column: CATEGORY1_
14:47:01,226 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
14:47:01,226 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:01,226 DEBUG BigIntegerType:122 - returning '725379' as column: SUBMITTER14_1_
14:47:01,242 DEBUG StringType:122 - returning 'Jodi Cobb' as column: SUBMITTER15_1_
14:47:01,242 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
14:47:01,242 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:01,242 DEBUG Loader:687 - result set row: 14
14:47:01,242 DEBUG BigIntegerType:122 - returning '15639' as column: PICTURE1_1_
14:47:01,242 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#15639]
14:47:01,242 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#15639]
14:47:01,242 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#15639]
14:47:01,242 DEBUG StringType:122 - returning 'POOR KNIGHTS ISLANDS;CRESTED BLENNY FISH;PARABLENNIUS LATICLAVIUS;COURTSHIP AND MATING (BY ANIMALS);CLOSE VIEWS' as column: SUBJECT1_
14:47:01,242 DEBUG StringType:122 - returning 'Near The Poor Knights Islands, New Zealand.' as column: LOCATION1_
14:47:01,257 DEBUG StringType:122 - returning 'Two Crested Blennies Share A Rock Pocket In Order To Mate.' as column: LEGEND1_
14:47:01,257 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:01,429 DEBUG TextType:122 - returning 'PACIFIC ISLANDS;POLYNESIA;NEW ZEALAND;POOR KNIGHTS ISLANDS;ANIMALS;FISHES;BLENNY FISH;CRESTED BLENNY FISH;PARABLENNIUS LATICLAVIUS;ANIMAL BEHAVIOR;COURTSHIP AND MATING (BY ANIMALS);PHOTOGRAPHY;CLOSE VIEWS;' as column: EXPANDED6_1_
14:47:01,429 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:01,429 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:01,429 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:01,429 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:01,429 DEBUG StringType:122 - returning 'ANIMALS' as column: CATEGORY1_
14:47:01,429 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
14:47:01,429 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:01,429 DEBUG BigIntegerType:122 - returning '400134' as column: SUBMITTER14_1_
14:47:01,429 DEBUG StringType:122 - returning 'David Doubilet' as column: SUBMITTER15_1_
14:47:01,445 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
14:47:01,445 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:01,445 DEBUG Loader:687 - result set row: 15
14:47:01,445 DEBUG BigIntegerType:122 - returning '15640' as column: PICTURE1_1_
14:47:01,445 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#15640]
14:47:01,445 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#15640]
14:47:01,445 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#15640]
14:47:01,445 DEBUG StringType:122 - returning 'STEWART ISLAND (NEW ZEALAND);HOOKER'S SEA LIONS;PHOCARCTOS HOOKERI;SWIMMING (BY ANIMALS);UNDERWATER PHOTOGRAPHY' as column: SUBJECT1_
14:47:01,445 DEBUG StringType:122 - returning 'Near Stewart Island, New Zealand.' as column: LOCATION1_
14:47:01,445 DEBUG StringType:122 - returning 'A Hooker'S Sea Lion Skims Through The Water.' as column: LEGEND1_
14:47:01,461 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:01,617 DEBUG TextType:122 - returning 'PACIFIC ISLANDS;POLYNESIA;NEW ZEALAND;STEWART ISLAND (NEW ZEALAND);ANIMALS;MAMMALS;MARINE MAMMALS;SEALS (MAMMALS);SEA LIONS;HOOKER'S SEA LIONS;PHOCARCTOS HOOKERI;ANIMAL BEHAVIOR;SWIMMING (BY ANIMALS);PHOTOGRAPHY;UNDERWATER PHOTOGRAPHY;' as column: EXPANDED6_1_
14:47:01,617 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:01,632 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:01,632 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:01,632 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:01,632 DEBUG StringType:122 - returning 'ANIMALS' as column: CATEGORY1_
14:47:01,632 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
14:47:01,632 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:01,632 DEBUG BigIntegerType:122 - returning '752907' as column: SUBMITTER14_1_
14:47:01,632 DEBUG StringType:122 - returning 'David Doubilet' as column: SUBMITTER15_1_
14:47:01,632 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
14:47:01,632 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:01,648 DEBUG Loader:687 - result set row: 16
14:47:01,648 DEBUG BigIntegerType:122 - returning '15641' as column: PICTURE1_1_
14:47:01,648 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#15641]
14:47:01,648 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#15641]
14:47:01,648 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#15641]
14:47:01,648 DEBUG StringType:122 - returning 'POOR KNIGHTS ISLANDS;CLOWN NUDIBRANCHS;CHROMODORIS AMOENA;COURTSHIP AND MATING (BY ANIMALS);CLOSE VIEWS;UNDERWATER PHOTOGRAPHY' as column: SUBJECT1_
14:47:01,648 DEBUG StringType:122 - returning 'Near The Poor Knights Islands, New Zealand.' as column: LOCATION1_
14:47:01,648 DEBUG StringType:122 - returning 'Two Clown Nudibranchs Mate.' as column: LEGEND1_
14:47:01,648 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:01,929 DEBUG TextType:122 - returning 'PACIFIC ISLANDS;POLYNESIA;NEW ZEALAND;POOR KNIGHTS ISLANDS;ANIMALS;MOLLUSKS;NUDIBRANCHS;CLOWN NUDIBRANCHS;CHROMODORIS AMOENA;ANIMAL BEHAVIOR;COURTSHIP AND MATING (BY ANIMALS);PHOTOGRAPHY;CLOSE VIEWS;UNDERWATER PHOTOGRAPHY;' as column: EXPANDED6_1_
14:47:01,929 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:01,929 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:01,929 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:01,929 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:01,929 DEBUG StringType:122 - returning 'ANIMALS' as column: CATEGORY1_
14:47:01,929 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:47:01,929 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:01,929 DEBUG BigIntegerType:122 - returning '696570' as column: SUBMITTER14_1_
14:47:01,945 DEBUG StringType:122 - returning 'David Doubilet' as column: SUBMITTER15_1_
14:47:01,945 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
14:47:01,945 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:01,945 DEBUG Loader:687 - result set row: 17
14:47:01,945 DEBUG BigIntegerType:122 - returning '20299' as column: PICTURE1_1_
14:47:01,945 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#20299]
14:47:01,945 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#20299]
14:47:01,945 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#20299]
14:47:01,945 DEBUG StringType:122 - returning 'SKATES (FOOTWEAR);SKATERS;SKATING;JUMPING (BY HUMANS);TROCADERO;INDEXERS' CHOICE PHOTOGRAPHS;PLAZAS AND SQUARES;EIFFEL TOWER (FRANCE)' as column: SUBJECT1_
14:47:01,945 DEBUG StringType:122 - returning 'Trocadero, Paris, France.' as column: LOCATION1_
14:47:01,945 DEBUG StringType:122 - returning 'A Skater Soars Across The Pavement Of The Trocadero In Paris.' as column: LEGEND1_
14:47:01,961 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:02,226 DEBUG TextType:122 - returning 'SPORTING GOODS;SKATES (FOOTWEAR);ENTERTAINERS;SKATERS;SPORTS;SKATING;HUMAN ACTIONS AND REACTIONS;JUMPING (BY HUMANS);EUROPE;FRANCE;ILE DE FRANCE REGION;VILLE DE PARIS DEPARTMENT;PARIS (FRANCE);TROCADERO;PHOTOGRAPHY;CHOICE PHOTOGRAPHS;INDEXERS' CHOICE PHOTOGRAPHS;PLAZAS AND SQUARES;EIFFEL TOWER (FRANCE);' as column: EXPANDED6_1_
14:47:02,226 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:02,226 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:02,226 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:02,226 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:02,226 DEBUG StringType:122 - returning 'PEOPLE,TRAVEL,WORLD CULTURES' as column: CATEGORY1_
14:47:02,242 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
14:47:02,242 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:02,242 DEBUG BigIntegerType:122 - returning '696570' as column: SUBMITTER14_1_
14:47:02,242 DEBUG StringType:122 - returning 'William Albert Allard' as column: SUBMITTER15_1_
14:47:02,242 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
14:47:02,242 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
14:47:02,242 DEBUG Loader:687 - result set row: 18
14:47:02,242 DEBUG BigIntegerType:122 - returning '20483' as column: PICTURE1_1_
14:47:02,242 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#20483]
14:47:02,242 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#20483]
14:47:02,258 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#20483]
14:47:02,258 DEBUG StringType:122 - returning 'RIKORIKO CAVE;UNDERWATER PHOTOGRAPHY;UNDERWATER REFUGES AND RESERVES;CLOSE VIEWS;RARE, ENDANGERED, AND THREATENED ANIMALS;LONG SPINED RED SEA URCHINS;DIADEMA PALMERI;KELP;UNDERWATER LIGHTS AND LIGHTING;UNDERWATER CAVES' as column: SUBJECT1_
14:47:02,258 DEBUG StringType:122 - returning 'Rikoriko Cave, Poor Knights Islands, New Zealand.' as column: LOCATION1_
14:47:02,258 DEBUG StringType:122 - returning 'Close View Of A Rare Long-Spined Red Urchin.' as column: LEGEND1_
14:47:02,258 DEBUG StringType:116 - returning null as column: SUMMARY1_
14:47:02,429 DEBUG TextType:122 - returning 'PACIFIC ISLANDS;POLYNESIA;NEW ZEALAND;POOR KNIGHTS ISLANDS;RIKORIKO CAVE;PHOTOGRAPHY;UNDERWATER PHOTOGRAPHY;REFUGES AND RESERVES;UNDERWATER REFUGES AND RESERVES;CLOSE VIEWS;ANIMALS;RARE, ENDANGERED, AND THREATENED ANIMALS;ECHINODERMS;SEA URCHINS;RED SEA URCHINS;LONG SPINED RED SEA URCHINS;DIADEMA PALMERI;PLANTS;AQUATIC PLANTS;KELP;LIGHTS AND LIGHTING;UNDERWATER LIGHTS AND LIGHTING;CAVES;UNDERWATER CAVES;' as column: EXPANDED6_1_
14:47:02,429 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
14:47:02,429 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
14:47:02,429 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
14:47:02,429 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
14:47:02,429 DEBUG StringType:122 - returning 'ANIMALS' as column: CATEGORY1_
14:47:02,429 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
14:47:02,445 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
14:47:02,445 DEBUG BigIntegerType:122 - returning '622190' as column: SUBMITTER14_1_
14:47:02,445 DEBUG StringType:122 - returning 'David Doubilet' as column: SUBMITTER15_1_
14:47:02,445 DEBUG StringType

fischman_98 · **Joined:** Thu May 18, 2006 2:58 pm **Posts:** 28

Thanks for the thoughts...below I posted the console output...please notice the timestamps to view the duration...

Any thoughts!?!?

BTW - I reduced the query results to limit the log output.

Thanks.

Matt

[code]15:05:49,942 DEBUG SessionImpl:219 - opened session at timestamp: 4728310783762432
15:05:49,942 DEBUG ThreadLocalSessionContext:290 - allowing method [beginTransaction] in non-transacted context
15:05:49,942 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [beginTransaction] to proceed to real session
15:05:49,942 DEBUG JDBCTransaction:54 - begin
15:05:49,942 DEBUG ConnectionManager:415 - opening JDBC connection
15:05:49,942 DEBUG JDBCTransaction:59 - current autocommit status: true
15:05:49,942 DEBUG JDBCTransaction:62 - disabling autocommit
15:05:49,958 DEBUG JDBCContext:194 - after transaction begin
am i here-->
15:05:49,989 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [beginTransaction] to proceed to real session
15:05:49,989 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [createQuery] to proceed to real session
15:05:49,989 DEBUG QueryPlanCache:69 - unable to locate HQL query plan in cache; generating (select count(*) from EPictureData)
15:05:49,989 DEBUG QueryTranslatorImpl:236 - parse() - HQL: select count(*) from org.hibernate.com.ngsimages.model.EPictureData
15:05:50,005 DEBUG AST:252 - --- HQL AST ---
\-[QUERY] 'query'
\-[SELECT_FROM] 'SELECT_FROM'
+-[FROM] 'from'
| \-[RANGE] 'RANGE'
| \-[DOT] '.'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[DOT] '.'
| | | | +-[DOT] '.'
| | | | | +-[IDENT] 'org'
| | | | | \-[IDENT] 'hibernate'
| | | | \-[IDENT] 'com'
| | | \-[IDENT] 'ngsimages'
| | \-[IDENT] 'model'
| \-[IDENT] 'EPictureData'
\-[SELECT] 'select'
\-[COUNT] 'count'
\-[ROW_STAR] '*'

15:05:50,005 DEBUG ErrorCounter:68 - throwQueryException() : no errors
15:05:50,005 DEBUG HqlSqlBaseWalker:111 - select << begin [level=1, statement=select]
15:05:50,005 DEBUG FromElement:104 - FromClause{level=1} : org.hibernate.com.ngsimages.model.EPictureData (no alias) -> epictureda0_
15:05:50,005 DEBUG HqlSqlBaseWalker:117 - select : finishing up [level=1, statement=select]
15:05:50,005 DEBUG HqlSqlWalker:511 - processQuery() : ( SELECT ( {select clause} ( count * ) ) ( FromClause{level=1} IRMAIR.E_PICTURE_DATA epictureda0_ ) )
15:05:50,021 DEBUG JoinProcessor:128 - Using FROM fragment [IRMAIR.E_PICTURE_DATA epictureda0_]
15:05:50,021 DEBUG HqlSqlBaseWalker:123 - select >> end [level=1, statement=select]
15:05:50,021 DEBUG AST:222 - --- SQL AST ---
\-[SELECT] QueryNode: 'SELECT' querySpaces (IRMAIR.E_PICTURE_DATA)
+-[SELECT_CLAUSE] SelectClause: '{select clause}'
| +-[COUNT] CountNode: 'count'
| | \-[ROW_STAR] SqlNode: '*'
| \-[SELECT_COLUMNS] SqlNode: ' as col_0_0_'
\-[FROM] FromClause: 'from' FromClause{level=1, fromElementCounter=1, fromElements=1, fromElementByClassAlias=[], fromElementByTableAlias=[epictureda0_], fromElementsByPath=[], collectionJoinFromElementsByPath=[], impliedElements=[]}
\-[FROM_FRAGMENT] FromElement: 'IRMAIR.E_PICTURE_DATA epictureda0_' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=IRMAIR.E_PICTURE_DATA,tableAlias=epictureda0_,origin=null,colums={,className=org.hibernate.com.ngsimages.model.EPictureData}}

15:05:50,021 DEBUG ErrorCounter:68 - throwQueryException() : no errors
15:05:50,021 DEBUG QueryTranslatorImpl:206 - HQL: select count(*) from org.hibernate.com.ngsimages.model.EPictureData
15:05:50,021 DEBUG QueryTranslatorImpl:207 - SQL: select count(*) as col_0_0_ from IRMAIR.E_PICTURE_DATA epictureda0_
15:05:50,021 DEBUG ErrorCounter:68 - throwQueryException() : no errors
15:05:50,021 DEBUG HQLQueryPlan:219 - HQL param location recognition took 0 mills (select count(*) from EPictureData)
15:05:50,021 DEBUG QueryPlanCache:75 - located HQL query plan in cache (select count(*) from EPictureData)
15:05:50,021 DEBUG HQLQueryPlan:148 - find: select count(*) from EPictureData
15:05:50,021 DEBUG QueryParameters:262 - named parameters: {}
15:05:50,021 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
15:05:50,021 DEBUG SQL:346 - select count(*) as col_0_0_ from IRMAIR.E_PICTURE_DATA epictureda0_
Hibernate: select count(*) as col_0_0_ from IRMAIR.E_PICTURE_DATA epictureda0_
15:05:50,036 DEBUG AbstractBatcher:424 - preparing statement
15:05:50,083 DEBUG AbstractBatcher:327 - about to open ResultSet (open ResultSets: 0, globally: 0)
15:05:50,083 DEBUG Loader:682 - processing result set
15:05:50,083 DEBUG Loader:687 - result set row: 0
15:05:50,083 DEBUG Loader:1164 - result row:
15:05:50,083 DEBUG IntegerType:122 - returning '15317' as column: col_0_0_
15:05:50,083 DEBUG Loader:709 - done processing result set (1 rows)
15:05:50,083 DEBUG AbstractBatcher:334 - about to close ResultSet (open ResultSets: 1, globally: 1)
15:05:50,083 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
15:05:50,083 DEBUG AbstractBatcher:470 - closing statement
15:05:50,083 DEBUG StatefulPersistenceContext:748 - initializing non-lazy collections
getColumns -> 1154372750302 ms.
getDataModel -> 1154372750302 ms.
startRow-->0 pageSize-->8
15:05:50,302 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [beginTransaction] to proceed to real session
15:05:50,302 DEBUG ThreadLocalSessionContext:300 - allowing proxied method [createQuery] to proceed to real session
15:05:50,317 DEBUG QueryPlanCache:69 - unable to locate HQL query plan in cache; generating (from EPictureData)
15:05:50,317 DEBUG QueryTranslatorImpl:236 - parse() - HQL: from org.hibernate.com.ngsimages.model.EPictureData
15:05:50,317 DEBUG AST:252 - --- HQL AST ---
\-[QUERY] 'query'
\-[SELECT_FROM] 'SELECT_FROM'
\-[FROM] 'from'
\-[RANGE] 'RANGE'
\-[DOT] '.'
+-[DOT] '.'
| +-[DOT] '.'
| | +-[DOT] '.'
| | | +-[DOT] '.'
| | | | +-[IDENT] 'org'
| | | | \-[IDENT] 'hibernate'
| | | \-[IDENT] 'com'
| | \-[IDENT] 'ngsimages'
| \-[IDENT] 'model'
\-[IDENT] 'EPictureData'

15:05:50,317 DEBUG ErrorCounter:68 - throwQueryException() : no errors
15:05:50,333 DEBUG HqlSqlBaseWalker:111 - select << begin [level=1, statement=select]
15:05:50,333 DEBUG FromElement:104 - FromClause{level=1} : org.hibernate.com.ngsimages.model.EPictureData (no alias) -> epictureda0_
15:05:50,333 DEBUG HqlSqlBaseWalker:117 - select : finishing up [level=1, statement=select]
15:05:50,333 DEBUG HqlSqlWalker:511 - processQuery() : ( SELECT ( FromClause{level=1} IRMAIR.E_PICTURE_DATA epictureda0_ ) )
15:05:50,333 DEBUG HqlSqlWalker:713 - Derived SELECT clause created.
15:05:50,333 DEBUG JoinProcessor:128 - Using FROM fragment [IRMAIR.E_PICTURE_DATA epictureda0_]
15:05:50,333 DEBUG HqlSqlBaseWalker:123 - select >> end [level=1, statement=select]
15:05:50,333 DEBUG AST:222 - --- SQL AST ---
\-[SELECT] QueryNode: 'SELECT' querySpaces (IRMAIR.E_PICTURE_DATA)
+-[SELECT_CLAUSE] SelectClause: '{derived select clause}'
| +-[SELECT_EXPR] SelectExpressionImpl: 'epictureda0_.PICTURE_ID as PICTURE1_1_' {FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=IRMAIR.E_PICTURE_DATA,tableAlias=epictureda0_,origin=null,colums={,className=org.hibernate.com.ngsimages.model.EPictureData}}}
| \-[SQL_TOKEN] SqlFragment: 'epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_'
\-[FROM] FromClause: 'from' FromClause{level=1, fromElementCounter=1, fromElements=1, fromElementByClassAlias=[], fromElementByTableAlias=[epictureda0_], fromElementsByPath=[], collectionJoinFromElementsByPath=[], impliedElements=[]}
\-[FROM_FRAGMENT] FromElement: 'IRMAIR.E_PICTURE_DATA epictureda0_' FromElement{explicit,not a collection join,not a fetch join,fetch non-lazy properties,classAlias=null,role=null,tableName=IRMAIR.E_PICTURE_DATA,tableAlias=epictureda0_,origin=null,colums={,className=org.hibernate.com.ngsimages.model.EPictureData}}

15:05:50,333 DEBUG ErrorCounter:68 - throwQueryException() : no errors
15:05:50,349 DEBUG QueryTranslatorImpl:206 - HQL: from org.hibernate.com.ngsimages.model.EPictureData
15:05:50,349 DEBUG QueryTranslatorImpl:207 - SQL: select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_
15:05:50,349 DEBUG ErrorCounter:68 - throwQueryException() : no errors
15:05:50,349 DEBUG HQLQueryPlan:219 - HQL param location recognition took 0 mills (from EPictureData)
time -> 47 ms.
15:05:50,349 DEBUG QueryPlanCache:75 - located HQL query plan in cache (from EPictureData)
15:05:50,349 DEBUG HQLQueryPlan:148 - find: from EPictureData
15:05:50,349 DEBUG QueryParameters:262 - named parameters: {}
15:05:50,349 DEBUG StandardQueryCache:93 - checking cached query results in region: org.hibernate.cache.StandardQueryCache
15:05:50,364 DEBUG EhCache:104 - key: sql: select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_; parameters: ; named parameters: {}; first row: 0; max rows: 24
15:05:50,364 DEBUG EhCache:113 - Element for sql: select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_; parameters: ; named parameters: {}; first row: 0; max rows: 24 is null
15:05:50,364 DEBUG StandardQueryCache:98 - query results were not found in cache
15:05:50,364 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
15:05:50,364 DEBUG SQL:346 - select * from ( select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_ ) where rownum <= ?
Hibernate: select * from ( select epictureda0_.PICTURE_ID as PICTURE1_1_, epictureda0_.SUBJECT as SUBJECT1_, epictureda0_.LOCATION as LOCATION1_, epictureda0_.LEGEND as LEGEND1_, epictureda0_.SUMMARY as SUMMARY1_, epictureda0_.EXPANDED_SUBJECT as EXPANDED6_1_, epictureda0_.MODEL_RELEASED as MODEL7_1_, epictureda0_.EXCLUSIVITY as EXCLUSIV8_1_, epictureda0_.TERRITORY_EXCLUSIVE as TERRITORY9_1_, epictureda0_.ICON_INDICATOR as ICON10_1_, epictureda0_.CATEGORY as CATEGORY1_, epictureda0_.ORIENTATION as ORIENTA12_1_, epictureda0_.ASSET_TYPE as ASSET13_1_, epictureda0_.SUBMITTER_ID as SUBMITTER14_1_, epictureda0_.SUBMITTER_NAME as SUBMITTER15_1_, epictureda0_.COPYRIGHT_CODE as COPYRIGHT16_1_, epictureda0_.COPYRIGHT_DESC as COPYRIGHT17_1_ from IRMAIR.E_PICTURE_DATA epictureda0_ ) where rownum <= ?
15:05:50,364 DEBUG AbstractBatcher:424 - preparing statement
15:05:50,380 DEBUG AbstractBatcher:327 - about to open ResultSet (open ResultSets: 0, globally: 0)
15:05:50,380 DEBUG Loader:682 - processing result set
15:05:50,380 DEBUG Loader:687 - result set row: 0
15:05:50,380 DEBUG BigIntegerType:122 - returning '1488' as column: PICTURE1_1_
15:05:50,380 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#1488]
15:05:50,380 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#1488]
15:05:50,380 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#1488]
15:05:50,396 DEBUG StringType:122 - returning 'MINGAN ARCHIPELAGO NATIONAL PARK PRESERVE;REFUGES AND RESERVES;NIAPISKAU ISLAND;BOARDWALKS AND PROMENADES;HIKERS AND HIKING;BAYS AND INLETS;SAINT LAWRENCE RIVER (QUEBEC);EVERGREEN TREES;SCENIC VIEWS;LIMESTONE;ROCK FORMATIONS;LICHENS;FORMAL GARDENS;PATHS' as column: SUBJECT1_
15:05:50,396 DEBUG StringType:122 - returning 'Mingan Archipelago National Park Reserve, Niapiskau Island, Mingan Islands, St. Lawrence River, Off The Coast Of Quebec, Canada.' as column: LOCATION1_
15:05:50,396 DEBUG StringType:122 - returning 'A Hiker Walks Along A Boardwalk On Niapiskau Island.' as column: LEGEND1_
15:05:50,396 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:50,568 DEBUG TextType:122 - returning 'NORTH AMERICA;CANADA;QUEBEC;NATIONAL PARKS (QUEBEC);MINGAN ARCHIPELAGO NATIONAL PARK PRESERVE;REFUGES AND RESERVES;MINGAN ISLANDS;NIAPISKAU ISLAND;WALKWAYS;BOARDWALKS AND PROMENADES;HIKERS AND HIKING;GEOGRAPHY;BAYS AND INLETS;SAINT LAWRENCE RIVER (QUEBEC);PLANTS;TREES;EVERGREEN TREES;SCENES AND VIEWS;SCENIC VIEWS;ROCK;LIMESTONE;ROCK FORMATIONS;LICHENS;GARDENS;FORMAL GARDENS;PATHS;' as column: EXPANDED6_1_
15:05:50,568 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:50,568 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:50,568 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:50,568 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:50,568 DEBUG StringType:122 - returning 'TRAVEL' as column: CATEGORY1_
15:05:50,568 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
15:05:50,583 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:50,583 DEBUG BigIntegerType:122 - returning '696570' as column: SUBMITTER14_1_
15:05:50,583 DEBUG StringType:122 - returning 'Michael Melford' as column: SUBMITTER15_1_
15:05:50,583 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
15:05:50,583 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:50,583 DEBUG Loader:687 - result set row: 1
15:05:50,583 DEBUG BigIntegerType:122 - returning '1549' as column: PICTURE1_1_
15:05:50,583 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#1549]
15:05:50,583 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#1549]
15:05:50,583 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#1549]
15:05:50,583 DEBUG StringType:122 - returning 'TREE ROOTS;RED MANGROVE TREES;RED MANGROVE SHRUBS;RHIZOPHORA MANGLE;ADAPTATION (BY PLANTS);REFUGES AND RESERVES;BISCAYNE BAY;BISCAYNE NATIONAL PARK;BAYS AND INLETS' as column: SUBJECT1_
15:05:50,599 DEBUG StringType:122 - returning 'Biscayne National Park, Biscayne Bay, Southern Florida.' as column: LOCATION1_
15:05:50,599 DEBUG StringType:122 - returning 'Root Legs Of Red Mangroves Extend Into Biscayne Bay. ' as column: LEGEND1_
15:05:50,599 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:50,755 DEBUG TextType:122 - returning 'PLANT PHYSIOLOGY;PLANT GROWTH AND DECAY;PLANT ROOTS;TREE ROOTS;PLANTS;TREES;MANGROVE TREES;RED MANGROVE TREES;SHRUBS;MANGROVE SHRUBS;RED MANGROVE SHRUBS;RHIZOPHORA MANGLE;ADAPTATION (BY PLANTS);REFUGES AND RESERVES;ATLANTIC OCEAN;BISCAYNE BAY;NORTH AMERICA;UNITED STATES;SOUTHERN STATES;FLORIDA;NATIONAL PARKS (FLORIDA);BISCAYNE NATIONAL PARK;GEOGRAPHY;BAYS AND INLETS;' as column: EXPANDED6_1_
15:05:50,771 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:50,771 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:50,771 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:50,771 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:50,771 DEBUG StringType:122 - returning 'NATURAL HISTORY' as column: CATEGORY1_
15:05:50,771 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
15:05:50,771 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:50,771 DEBUG BigIntegerType:122 - returning '401005' as column: SUBMITTER14_1_
15:05:50,771 DEBUG StringType:122 - returning 'Medford Taylor' as column: SUBMITTER15_1_
15:05:50,771 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
15:05:50,786 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:50,786 DEBUG Loader:687 - result set row: 2
15:05:50,786 DEBUG BigIntegerType:122 - returning '2630' as column: PICTURE1_1_
15:05:50,786 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2630]
15:05:50,786 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2630]
15:05:50,786 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2630]
15:05:50,786 DEBUG StringType:122 - returning 'MISSOURI RIVER (MONTANA);WHITE CLIFFS (MONTANA);AERIAL VIEWS;LANDSCAPES;RIVERS;TWILIGHT VIEWS' as column: SUBJECT1_
15:05:50,786 DEBUG StringType:122 - returning 'White Cliffs Region, Missouri River, Montana.' as column: LOCATION1_
15:05:50,786 DEBUG StringType:122 - returning 'Aerial Of The Missouri River In Montana'S White Cliffs Region.' as column: LEGEND1_
15:05:50,786 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:51,068 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;MONTANA;MISSOURI RIVER (MONTANA);WHITE CLIFFS (MONTANA);SCENES AND VIEWS;AERIAL VIEWS;LANDSCAPES;GEOGRAPHY;RIVERS;TWILIGHT VIEWS;' as column: EXPANDED6_1_
15:05:51,068 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:51,068 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:51,068 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:51,068 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:51,068 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
15:05:51,068 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:51,068 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:51,068 DEBUG BigIntegerType:122 - returning '403879' as column: SUBMITTER14_1_
15:05:51,068 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:51,083 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:51,083 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:51,083 DEBUG Loader:687 - result set row: 3
15:05:51,083 DEBUG BigIntegerType:122 - returning '2631' as column: PICTURE1_1_
15:05:51,083 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2631]
15:05:51,083 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2631]
15:05:51,083 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2631]
15:05:51,083 DEBUG StringType:122 - returning 'COLUMBIA RIVER (WASHINGTON);SUN;CLOUDS;TWILIGHT VIEWS;RIVERS' as column: SUBJECT1_
15:05:51,083 DEBUG StringType:122 - returning 'Columbia River, Washington.' as column: LOCATION1_
15:05:51,083 DEBUG StringType:122 - returning 'Early Morning Sun And Clouds Over The Mouth Of The Columbia River.' as column: LEGEND1_
15:05:51,099 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:51,255 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;PACIFIC COAST STATES;WASHINGTON;COLUMBIA RIVER (WASHINGTON);SUN;CLOUDS;SCENES AND VIEWS;TWILIGHT VIEWS;GEOGRAPHY;RIVERS;' as column: EXPANDED6_1_
15:05:51,271 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:51,271 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:51,271 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:51,271 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:51,271 DEBUG StringType:122 - returning 'NATURAL HISTORY' as column: CATEGORY1_
15:05:51,271 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:51,271 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:51,271 DEBUG BigIntegerType:122 - returning '752907' as column: SUBMITTER14_1_
15:05:51,271 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:51,271 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:51,286 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:51,286 DEBUG Loader:687 - result set row: 4
15:05:51,286 DEBUG BigIntegerType:122 - returning '2633' as column: PICTURE1_1_
15:05:51,286 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2633]
15:05:51,286 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2633]
15:05:51,286 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2633]
15:05:51,286 DEBUG StringType:122 - returning 'OREGON;PACIFIC OCEAN;TWILIGHT VIEWS;CLOUDS;SCENIC VIEWS;BEACHES' as column: SUBJECT1_
15:05:51,286 DEBUG StringType:122 - returning 'Pacific Ocean, Oregon Coast.' as column: LOCATION1_
15:05:51,286 DEBUG StringType:122 - returning 'Twilight View Of Pacific Ocean From Oregon Coast.' as column: LEGEND1_
15:05:51,286 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:51,458 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;PACIFIC COAST STATES;OREGON;PACIFIC OCEAN;SCENES AND VIEWS;TWILIGHT VIEWS;CLOUDS;SCENIC VIEWS;GEOGRAPHY;BEACHES;' as column: EXPANDED6_1_
15:05:51,474 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:51,474 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:51,474 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:51,474 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:51,474 DEBUG StringType:122 - returning 'NATURAL HISTORY' as column: CATEGORY1_
15:05:51,474 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:51,474 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:51,489 DEBUG BigIntegerType:122 - returning '695140' as column: SUBMITTER14_1_
15:05:51,489 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:51,489 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:51,489 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:51,489 DEBUG Loader:687 - result set row: 5
15:05:51,489 DEBUG BigIntegerType:122 - returning '2637' as column: PICTURE1_1_
15:05:51,489 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2637]
15:05:51,489 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2637]
15:05:51,489 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2637]
15:05:51,489 DEBUG StringType:122 - returning 'LEMHI PASS (MONTANA);PASSES;GRASSES' as column: SUBJECT1_
15:05:51,489 DEBUG StringType:122 - returning 'Lemhi Pass, Montana.' as column: LOCATION1_
15:05:51,505 DEBUG StringType:122 - returning 'Grassy Lemhi Pass.' as column: LEGEND1_
15:05:51,505 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:51,771 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;MONTANA;ROCKY MOUNTAINS (MONTANA);BITTERROOT RANGE (MONTANA);LEMHI PASS (MONTANA);PASSES;PLANTS;GRASSES;' as column: EXPANDED6_1_
15:05:51,771 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:51,771 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:51,771 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:51,771 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:51,771 DEBUG StringType:122 - returning 'NATURAL' as column: CATEGORY1_
15:05:51,771 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:51,771 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:51,771 DEBUG BigIntegerType:122 - returning '400134' as column: SUBMITTER14_1_
15:05:51,786 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:51,786 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:51,786 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:51,786 DEBUG Loader:687 - result set row: 6
15:05:51,786 DEBUG BigIntegerType:122 - returning '2639' as column: PICTURE1_1_
15:05:51,786 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2639]
15:05:51,786 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2639]
15:05:51,786 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2639]
15:05:51,786 DEBUG StringType:122 - returning 'BITTERROOT RANGE (MONTANA);MOUNTAINS;FLOWERING FIELDS (WILDFLOWERS);WILDFLOWERS;MEADOWS;SCENIC VIEWS;LANDSCAPES' as column: SUBJECT1_
15:05:51,786 DEBUG StringType:122 - returning 'Bitterroot Range, Montana.' as column: LOCATION1_
15:05:51,786 DEBUG StringType:122 - returning 'View From Wildflower Meadow Of The Rockies' Bitterroot Range.' as column: LEGEND1_
15:05:51,802 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:51,974 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;MONTANA;ROCKY MOUNTAINS (MONTANA);BITTERROOT RANGE (MONTANA);GEOGRAPHY;MOUNTAINS;FIELDS;FLOWERING FIELDS (WILDFLOWERS);PLANTS;FLOWERS;WILDFLOWERS;GRASSLANDS;MEADOWS;SCENES AND VIEWS;SCENIC VIEWS;LANDSCAPES;' as column: EXPANDED6_1_
15:05:51,974 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:51,974 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:51,974 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:51,974 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:51,974 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
15:05:51,974 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:51,974 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:51,974 DEBUG BigIntegerType:122 - returning '699225' as column: SUBMITTER14_1_
15:05:51,974 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:51,974 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:51,989 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:51,989 DEBUG Loader:687 - result set row: 7
15:05:51,989 DEBUG BigIntegerType:122 - returning '2642' as column: PICTURE1_1_
15:05:51,989 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2642]
15:05:51,989 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2642]
15:05:51,989 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2642]
15:05:51,989 DEBUG StringType:122 - returning 'WEIPPE PRAIRIE;EVERGREEN TREES;PRAIRIES' as column: SUBJECT1_
15:05:51,989 DEBUG StringType:122 - returning 'Weippe Prairie, Idaho.' as column: LOCATION1_
15:05:51,989 DEBUG StringType:122 - returning 'A Lone Evergreen Tree Stands Tall On The Weippe Prairie.' as column: LEGEND1_
15:05:51,989 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:52,161 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;IDAHO;WEIPPE PRAIRIE;PLANTS;TREES;EVERGREEN TREES;GEOGRAPHY;GRASSLANDS;PRAIRIES;' as column: EXPANDED6_1_
15:05:52,177 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:52,177 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:52,177 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:52,177 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:52,177 DEBUG StringType:122 - returning 'CONCEPTS,NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
15:05:52,177 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:52,177 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:52,177 DEBUG BigIntegerType:122 - returning '699225' as column: SUBMITTER14_1_
15:05:52,177 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:52,177 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:52,177 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:52,193 DEBUG Loader:687 - result set row: 8
15:05:52,193 DEBUG BigIntegerType:122 - returning '2644' as column: PICTURE1_1_
15:05:52,193 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2644]
15:05:52,193 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2644]
15:05:52,193 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2644]
15:05:52,193 DEBUG StringType:122 - returning 'COLUMBIA RIVER (WASHINGTON);CONFLUENCES;SKY;TWILIGHT VIEWS' as column: SUBJECT1_
15:05:52,193 DEBUG StringType:122 - returning 'Near The Confluence Of The Snake And Columbia Rivers, Washington.' as column: LOCATION1_
15:05:52,193 DEBUG StringType:122 - returning 'Twilight Sky Over The Columbia River.' as column: LEGEND1_
15:05:52,193 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:52,364 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;PACIFIC COAST STATES;WASHINGTON;COLUMBIA RIVER (WASHINGTON);GEOGRAPHY;CONFLUENCES;SKY;SCENES AND VIEWS;TWILIGHT VIEWS;' as column: EXPANDED6_1_
15:05:52,364 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:52,364 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:52,380 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:52,380 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:52,380 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
15:05:52,380 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:52,380 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:52,380 DEBUG BigIntegerType:122 - returning '957504' as column: SUBMITTER14_1_
15:05:52,380 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:52,380 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:52,380 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:52,396 DEBUG Loader:687 - result set row: 9
15:05:52,396 DEBUG BigIntegerType:122 - returning '2645' as column: PICTURE1_1_
15:05:52,396 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2645]
15:05:52,396 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2645]
15:05:52,396 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2645]
15:05:52,396 DEBUG StringType:122 - returning 'TWO SISTERS (IDAHO);BUTTES;ROCK FORMATIONS' as column: SUBJECT1_
15:05:52,396 DEBUG StringType:122 - returning 'Two Sisters, Idaho.' as column: LOCATION1_
15:05:52,396 DEBUG StringType:122 - returning 'Two Sisters Buttes Landmark.' as column: LEGEND1_
15:05:52,396 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:52,568 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;IDAHO;TWO SISTERS (IDAHO);GEOGRAPHY;HILLS;BUTTES;ROCK FORMATIONS;' as column: EXPANDED6_1_
15:05:52,568 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:52,568 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:52,568 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:52,583 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:52,583 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
15:05:52,583 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:52,583 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:52,583 DEBUG BigIntegerType:122 - returning '695140' as column: SUBMITTER14_1_
15:05:52,583 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:52,583 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:52,583 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:52,599 DEBUG Loader:687 - result set row: 10
15:05:52,599 DEBUG BigIntegerType:122 - returning '2653' as column: PICTURE1_1_
15:05:52,599 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#2653]
15:05:52,599 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#2653]
15:05:52,599 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#2653]
15:05:52,599 DEBUG StringType:122 - returning 'SQUARE BUTTE;BUTTES;SCENIC VIEWS;THE MOON;TWILIGHT VIEWS;LANDSCAPES;DESOLATE LANDSCAPES' as column: SUBJECT1_
15:05:52,599 DEBUG StringType:122 - returning 'Square Butte, Montana.' as column: LOCATION1_
15:05:52,599 DEBUG StringType:122 - returning 'Moonlight Gilds Montana'S Square Butte.' as column: LEGEND1_
15:05:52,599 DEBUG StringType:122 - returning 'This Landmark Guided Lewis Toward The Rockies On His Way West And On His Way Home As He Left The Mountains.' as column: SUMMARY1_
15:05:52,771 DEBUG TextType:122 - returning 'NORTH AMERICA;UNITED STATES;ROCKY MOUNTAIN STATES;MONTANA;SQUARE BUTTE;GEOGRAPHY;HILLS;BUTTES;SCENES AND VIEWS;SCENIC VIEWS;MOONS;THE MOON;TWILIGHT VIEWS;LANDSCAPES;DESOLATE LANDSCAPES;' as column: EXPANDED6_1_
15:05:52,771 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:52,771 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:52,771 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:52,771 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:52,786 DEBUG StringType:122 - returning 'NATURAL HISTORY,TRAVEL' as column: CATEGORY1_
15:05:52,786 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:52,786 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:52,786 DEBUG BigIntegerType:122 - returning '406543' as column: SUBMITTER14_1_
15:05:52,786 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:52,786 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:52,786 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:52,786 DEBUG Loader:687 - result set row: 11
15:05:52,786 DEBUG BigIntegerType:122 - returning '4128' as column: PICTURE1_1_
15:05:52,786 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#4128]
15:05:52,786 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#4128]
15:05:52,802 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#4128]
15:05:52,802 DEBUG StringType:122 - returning 'ENGLAND;STRATFORD UPON AVON;SHAKESPEAREAN GARDENS;DOMESTIC SHORTHAIR CATS;SHAKESPEARE, WILLIAM;HERBS AND SPICES' as column: SUBJECT1_
15:05:52,802 DEBUG StringType:122 - returning 'Stratford-Upon-Avon, England.' as column: LOCATION1_
15:05:52,802 DEBUG StringType:122 - returning 'White Cat Perched On A Fence Overlooking The Gardens At Stratford-Upon-Avon.' as column: LEGEND1_
15:05:52,802 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:52,974 DEBUG TextType:122 - returning 'EUROPE;GREAT BRITAIN;ENGLAND;WARWICKSHIRE (COUNTY IN ENGLAND);STRATFORD UPON AVON;NORTH AMERICA;CANADA;ONTARIO;SHAKESPEAREAN GARDENS;ANIMALS;MAMMALS;FELINES;DOMESTIC CATS;DOMESTIC SHORTHAIR CATS;SHAKESPEARE, WILLIAM;HERBS AND SPICES;' as column: EXPANDED6_1_
15:05:52,974 DEBUG CharacterType:116 - returning null as column: MODEL7_1_
15:05:52,974 DEBUG CharacterType:116 - returning null as column: EXCLUSIV8_1_
15:05:52,974 DEBUG CharacterType:116 - returning null as column: TERRITORY9_1_
15:05:52,974 DEBUG CharacterType:116 - returning null as column: ICON10_1_
15:05:52,974 DEBUG StringType:116 - returning null as column: CATEGORY1_
15:05:52,974 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
15:05:52,989 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:52,989 DEBUG BigIntegerType:122 - returning '91627' as column: SUBMITTER14_1_
15:05:52,989 DEBUG StringType:122 - returning 'Sam Abell' as column: SUBMITTER15_1_
15:05:52,989 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:52,989 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:52,989 DEBUG Loader:687 - result set row: 12
15:05:52,989 DEBUG BigIntegerType:122 - returning '7269' as column: PICTURE1_1_
15:05:52,989 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#7269]
15:05:52,989 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#7269]
15:05:52,989 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#7269]
15:05:53,005 DEBUG StringType:122 - returning 'HUMAN BONES AND SKELETONS;NATIONAL GEOGRAPHIC SOCIETY RESEARCH GRANTS;CLOSE VIEWS;VOLCANIC ERUPTIONS;SKULLS;HERCULANEUM' as column: SUBJECT1_
15:05:53,005 DEBUG StringType:122 - returning 'Herculaneum, Italy.' as column: LOCATION1_
15:05:53,005 DEBUG StringType:122 - returning 'A Close View Of Embracing Skeletons Excavated On Herculaneum'S Beach.' as column: LEGEND1_
15:05:53,005 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:53,177 DEBUG TextType:122 - returning 'BONES AND SKELETONS;HUMAN BONES AND SKELETONS;NATIONAL GEOGRAPHIC SOCIETY;NATIONAL GEOGRAPHIC SOCIETY ACTIVITIES;NATIONAL GEOGRAPHIC SOCIETY RESEARCH GRANTS;PHOTOGRAPHY;CLOSE VIEWS;NATURAL FORCES AND PHENOMENA;VOLCANIC ERUPTIONS;SKULLS;EUROPE;ITALY;CAMPANIA REGION;HERCULANEUM;' as column: EXPANDED6_1_
15:05:53,177 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:53,177 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:53,177 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:53,177 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:53,177 DEBUG StringType:122 - returning 'PEOPLE,WORLD CULTURES' as column: CATEGORY1_
15:05:53,177 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
15:05:53,193 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:53,193 DEBUG BigIntegerType:122 - returning '406543' as column: SUBMITTER14_1_
15:05:53,193 DEBUG StringType:122 - returning 'O. Louis Mazzatenta' as column: SUBMITTER15_1_
15:05:53,193 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:53,193 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:53,193 DEBUG Loader:687 - result set row: 13
15:05:53,193 DEBUG BigIntegerType:122 - returning '8268' as column: PICTURE1_1_
15:05:53,193 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#8268]
15:05:53,193 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#8268]
15:05:53,193 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#8268]
15:05:53,208 DEBUG StringType:122 - returning 'HYDE PARK (ENGLAND);SHADOWS;BENCHES AND CHAIRS;OUTDOOR FURNISHINGS;SUNBATHING (BY HUMANS);NATIONAL PRESS PHOTOGRAPHERS ASSOCIATION CONTEST 1984;WHITE HOUSE PHOTOGRAPHERS ASSOCIATION CONTEST 1985;REAR VIEWS' as column: SUBJECT1_
15:05:53,208 DEBUG StringType:122 - returning 'Hyde Park, London, England.' as column: LOCATION1_
15:05:53,208 DEBUG StringType:122 - returning 'Silhouette Of A Man Sitting In A Folding Chair On The The Serpentine.' as column: LEGEND1_
15:05:53,208 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:53,474 DEBUG TextType:122 - returning 'EUROPE;GREAT BRITAIN;ENGLAND;GREATER LONDON (COUNTY IN ENGLAND);LONDON (ENGLAND);HYDE PARK (ENGLAND);SHADOWS;FURNISHINGS;BENCHES AND CHAIRS;OUTDOOR FURNISHINGS;HUMAN ACTIONS AND REACTIONS;SUNBATHING (BY HUMANS);NATIONAL PRESS PHOTOGRAPHERS ASSOCIATION CONTEST 1984;WHITE HOUSE PHOTOGRAPHERS ASSOCIATION CONTEST 1985;SCENES AND VIEWS;REAR VIEWS;' as column: EXPANDED6_1_
15:05:53,474 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:53,474 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:53,474 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:53,474 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:53,489 DEBUG StringType:122 - returning 'PEOPLE,WORLD CULTURES' as column: CATEGORY1_
15:05:53,489 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
15:05:53,489 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:53,489 DEBUG BigIntegerType:122 - returning '725379' as column: SUBMITTER14_1_
15:05:53,489 DEBUG StringType:122 - returning 'Jodi Cobb' as column: SUBMITTER15_1_
15:05:53,489 DEBUG StringType:122 - returning '04' as column: COPYRIGHT16_1_
15:05:53,489 DEBUG StringType:122 - returning 'NO PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:53,489 DEBUG Loader:687 - result set row: 14
15:05:53,489 DEBUG BigIntegerType:122 - returning '15639' as column: PICTURE1_1_
15:05:53,489 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#15639]
15:05:53,489 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#15639]
15:05:53,505 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#15639]
15:05:53,505 DEBUG StringType:122 - returning 'POOR KNIGHTS ISLANDS;CRESTED BLENNY FISH;PARABLENNIUS LATICLAVIUS;COURTSHIP AND MATING (BY ANIMALS);CLOSE VIEWS' as column: SUBJECT1_
15:05:53,505 DEBUG StringType:122 - returning 'Near The Poor Knights Islands, New Zealand.' as column: LOCATION1_
15:05:53,505 DEBUG StringType:122 - returning 'Two Crested Blennies Share A Rock Pocket In Order To Mate.' as column: LEGEND1_
15:05:53,505 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:53,771 DEBUG TextType:122 - returning 'PACIFIC ISLANDS;POLYNESIA;NEW ZEALAND;POOR KNIGHTS ISLANDS;ANIMALS;FISHES;BLENNY FISH;CRESTED BLENNY FISH;PARABLENNIUS LATICLAVIUS;ANIMAL BEHAVIOR;COURTSHIP AND MATING (BY ANIMALS);PHOTOGRAPHY;CLOSE VIEWS;' as column: EXPANDED6_1_
15:05:53,786 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:53,786 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:53,786 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:53,786 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:53,786 DEBUG StringType:122 - returning 'ANIMALS' as column: CATEGORY1_
15:05:53,786 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
15:05:53,786 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:53,786 DEBUG BigIntegerType:122 - returning '400134' as column: SUBMITTER14_1_
15:05:53,786 DEBUG StringType:122 - returning 'David Doubilet' as column: SUBMITTER15_1_
15:05:53,786 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
15:05:53,802 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:53,802 DEBUG Loader:687 - result set row: 15
15:05:53,802 DEBUG BigIntegerType:122 - returning '15640' as column: PICTURE1_1_
15:05:53,802 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#15640]
15:05:53,802 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#15640]
15:05:53,802 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#15640]
15:05:53,802 DEBUG StringType:122 - returning 'STEWART ISLAND (NEW ZEALAND);HOOKER'S SEA LIONS;PHOCARCTOS HOOKERI;SWIMMING (BY ANIMALS);UNDERWATER PHOTOGRAPHY' as column: SUBJECT1_
15:05:53,802 DEBUG StringType:122 - returning 'Near Stewart Island, New Zealand.' as column: LOCATION1_
15:05:53,802 DEBUG StringType:122 - returning 'A Hooker'S Sea Lion Skims Through The Water.' as column: LEGEND1_
15:05:53,802 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:53,974 DEBUG TextType:122 - returning 'PACIFIC ISLANDS;POLYNESIA;NEW ZEALAND;STEWART ISLAND (NEW ZEALAND);ANIMALS;MAMMALS;MARINE MAMMALS;SEALS (MAMMALS);SEA LIONS;HOOKER'S SEA LIONS;PHOCARCTOS HOOKERI;ANIMAL BEHAVIOR;SWIMMING (BY ANIMALS);PHOTOGRAPHY;UNDERWATER PHOTOGRAPHY;' as column: EXPANDED6_1_
15:05:53,974 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:53,990 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:53,990 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:53,990 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:53,990 DEBUG StringType:122 - returning 'ANIMALS' as column: CATEGORY1_
15:05:53,990 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
15:05:53,990 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:53,990 DEBUG BigIntegerType:122 - returning '752907' as column: SUBMITTER14_1_
15:05:53,990 DEBUG StringType:122 - returning 'David Doubilet' as column: SUBMITTER15_1_
15:05:53,990 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
15:05:53,990 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:54,005 DEBUG Loader:687 - result set row: 16
15:05:54,005 DEBUG BigIntegerType:122 - returning '15641' as column: PICTURE1_1_
15:05:54,005 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#15641]
15:05:54,005 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#15641]
15:05:54,005 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#15641]
15:05:54,005 DEBUG StringType:122 - returning 'POOR KNIGHTS ISLANDS;CLOWN NUDIBRANCHS;CHROMODORIS AMOENA;COURTSHIP AND MATING (BY ANIMALS);CLOSE VIEWS;UNDERWATER PHOTOGRAPHY' as column: SUBJECT1_
15:05:54,005 DEBUG StringType:122 - returning 'Near The Poor Knights Islands, New Zealand.' as column: LOCATION1_
15:05:54,005 DEBUG StringType:122 - returning 'Two Clown Nudibranchs Mate.' as column: LEGEND1_
15:05:54,005 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:54,177 DEBUG TextType:122 - returning 'PACIFIC ISLANDS;POLYNESIA;NEW ZEALAND;POOR KNIGHTS ISLANDS;ANIMALS;MOLLUSKS;NUDIBRANCHS;CLOWN NUDIBRANCHS;CHROMODORIS AMOENA;ANIMAL BEHAVIOR;COURTSHIP AND MATING (BY ANIMALS);PHOTOGRAPHY;CLOSE VIEWS;UNDERWATER PHOTOGRAPHY;' as column: EXPANDED6_1_
15:05:54,177 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:54,193 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:54,193 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:54,193 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:54,193 DEBUG StringType:122 - returning 'ANIMALS' as column: CATEGORY1_
15:05:54,193 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:54,193 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:54,193 DEBUG BigIntegerType:122 - returning '696570' as column: SUBMITTER14_1_
15:05:54,208 DEBUG StringType:122 - returning 'David Doubilet' as column: SUBMITTER15_1_
15:05:54,208 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
15:05:54,208 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:54,208 DEBUG Loader:687 - result set row: 17
15:05:54,208 DEBUG BigIntegerType:122 - returning '20299' as column: PICTURE1_1_
15:05:54,208 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#20299]
15:05:54,208 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#20299]
15:05:54,208 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#20299]
15:05:54,208 DEBUG StringType:122 - returning 'SKATES (FOOTWEAR);SKATERS;SKATING;JUMPING (BY HUMANS);TROCADERO;INDEXERS' CHOICE PHOTOGRAPHS;PLAZAS AND SQUARES;EIFFEL TOWER (FRANCE)' as column: SUBJECT1_
15:05:54,208 DEBUG StringType:122 - returning 'Trocadero, Paris, France.' as column: LOCATION1_
15:05:54,208 DEBUG StringType:122 - returning 'A Skater Soars Across The Pavement Of The Trocadero In Paris.' as column: LEGEND1_
15:05:54,224 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:54,380 DEBUG TextType:122 - returning 'SPORTING GOODS;SKATES (FOOTWEAR);ENTERTAINERS;SKATERS;SPORTS;SKATING;HUMAN ACTIONS AND REACTIONS;JUMPING (BY HUMANS);EUROPE;FRANCE;ILE DE FRANCE REGION;VILLE DE PARIS DEPARTMENT;PARIS (FRANCE);TROCADERO;PHOTOGRAPHY;CHOICE PHOTOGRAPHS;INDEXERS' CHOICE PHOTOGRAPHS;PLAZAS AND SQUARES;EIFFEL TOWER (FRANCE);' as column: EXPANDED6_1_
15:05:54,380 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:54,380 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:54,380 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:54,380 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:54,380 DEBUG StringType:122 - returning 'PEOPLE,TRAVEL,WORLD CULTURES' as column: CATEGORY1_
15:05:54,396 DEBUG StringType:122 - returning 'Landscape' as column: ORIENTA12_1_
15:05:54,396 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:54,396 DEBUG BigIntegerType:122 - returning '696570' as column: SUBMITTER14_1_
15:05:54,396 DEBUG StringType:122 - returning 'William Albert Allard' as column: SUBMITTER15_1_
15:05:54,396 DEBUG StringType:122 - returning '51' as column: COPYRIGHT16_1_
15:05:54,396 DEBUG StringType:122 - returning 'IS - NEED PAYMENT, NO PERMISSION' as column: COPYRIGHT17_1_
15:05:54,396 DEBUG Loader:687 - result set row: 18
15:05:54,396 DEBUG BigIntegerType:122 - returning '20483' as column: PICTURE1_1_
15:05:54,396 DEBUG Loader:1164 - result row: EntityKey[org.hibernate.com.ngsimages.model.EPictureData#20483]
15:05:54,396 DEBUG Loader:1347 - Initializing object from ResultSet: [org.hibernate.com.ngsimages.model.EPictureData#20483]
15:05:54,396 DEBUG AbstractEntityPersister:1860 - Hydrating entity: [org.hibernate.com.ngsimages.model.EPictureData#20483]
15:05:54,411 DEBUG StringType:122 - returning 'RIKORIKO CAVE;UNDERWATER PHOTOGRAPHY;UNDERWATER REFUGES AND RESERVES;CLOSE VIEWS;RARE, ENDANGERED, AND THREATENED ANIMALS;LONG SPINED RED SEA URCHINS;DIADEMA PALMERI;KELP;UNDERWATER LIGHTS AND LIGHTING;UNDERWATER CAVES' as column: SUBJECT1_
15:05:54,411 DEBUG StringType:122 - returning 'Rikoriko Cave, Poor Knights Islands, New Zealand.' as column: LOCATION1_
15:05:54,411 DEBUG StringType:122 - returning 'Close View Of A Rare Long-Spined Red Urchin.' as column: LEGEND1_
15:05:54,411 DEBUG StringType:116 - returning null as column: SUMMARY1_
15:05:54,583 DEBUG TextType:122 - returning 'PACIFIC ISLANDS;POLYNESIA;NEW ZEALAND;POOR KNIGHTS ISLANDS;RIKORIKO CAVE;PHOTOGRAPHY;UNDERWATER PHOTOGRAPHY;REFUGES AND RESERVES;UNDERWATER REFUGES AND RESERVES;CLOSE VIEWS;ANIMALS;RARE, ENDANGERED, AND THREATENED ANIMALS;ECHINODERMS;SEA URCHINS;RED SEA URCHINS;LONG SPINED RED SEA URCHINS;DIADEMA PALMERI;PLANTS;AQUATIC PLANTS;KELP;LIGHTS AND LIGHTING;UNDERWATER LIGHTS AND LIGHTING;CAVES;UNDERWATER CAVES;' as column: EXPANDED6_1_
15:05:54,583 DEBUG CharacterType:122 - returning 'N' as column: MODEL7_1_
15:05:54,583 DEBUG CharacterType:122 - returning 'N' as column: EXCLUSIV8_1_
15:05:54,583 DEBUG CharacterType:122 - returning 'N' as column: TERRITORY9_1_
15:05:54,583 DEBUG CharacterType:122 - returning 'N' as column: ICON10_1_
15:05:54,583 DEBUG StringType:122 - returning 'ANIMALS' as column: CATEGORY1_
15:05:54,583 DEBUG StringType:122 - returning 'Portrait' as column: ORIENTA12_1_
15:05:54,599 DEBUG BigIntegerType:122 - returning '1' as column: ASSET13_1_
15:05:54,599 DEBUG BigIntegerType:122 - returning '622190' as column: SUBMITTER14_1_
15:05:54,599 DEBUG StringType:122 - returning 'David Doubilet' as colum

jherbst · **Posted:** Mon Jul 31, 2006 4:27 pm

As far as I can see, there is only a duration of 5 seconds (not 20 as you mentioned in you're first post). But this is also pretty long.
To analyze this you should check you'r logging. Is there a time difference if you turn the hibernate logging off (or less verbose)?
Have you also checked you're JDBC driver? It seems to take pretty long to get the next row in you're resultset, maybe you should change the driver.
At last try using a batch-size in you're mapping file. Maybe you can also some milliseconds their.

fischman_98 · **Joined:** Thu May 18, 2006 2:58 pm **Posts:** 28

Thanks for the feedback...When I did the reply with the log trace, I got a MySql error from the forum, so I reposted and noticed it got posted twice :( Sorry for the inconvenience.

Anyhow, I reduced the number of rows queried in an attempt to fit all the log text (didn't work) in the post....but I am looking into driver issues now...

The loading seems to be the issue, so I'm going to search for loading issues unsing "RDBMS: Oracle, version: Oracle8i Enterprise Edition Release 8.1.6.0.0 - Production" and "JDBC driver: Oracle JDBC driver, version: 8.1.7.0.0".

Let me know if you think of anything.

Thanks.

fischman_98 · **Joined:** Thu May 18, 2006 2:58 pm **Posts:** 28

Issue resolved - The Clob column was causing the mapping to be extremely slow. Once the column was removed from the mapping, the queries were "lightening fast"!

I presume it is a problem with Oracle and/or the Oracle jdbc driver.

Thanks to all for your input.

JayadevS · **Joined:** Tue Jul 21, 2009 5:07 am **Posts:** 3

Hi,

Really the information posted above is uiseful.

One query, I am trying to cache named queries in JPA, to cache the Named Query results for a particular time period of more than 20 minutes.

I have written the ehcache.xml and defined the same reference in the persistence.xml, In the Entity option object, for the Named Query I have defined the Hints --> QueryHint = cacheable to true and the region.

But the query is being cached for only 2 minutes(120seconds). I would like to cache the query about 20 minutes.

Please find the below are the code in persistence.xml, ehcache.xml, POJO(Example: Option.java) and DAO(OptionDAO.java).

POJO(Option.java):

@NamedQuery(name = "findOptionsByLineOfBusinessAndStatus", query = "from Option where uniqueOptionKey.lineOfBusinessId= :lineOfBusiness AND optionStatus= :stateCode", hints = { @QueryHint(name = "org.hibernate.cacheable", value = "true"), @QueryHint(name = "org.hibernate.cacheRegion", value = "query.AdministrativeAreasPerCountry") })

persistence.xml:

<property name="hibernate.cache.use_second_level_cache" value="true"/>
<property name="hibernate.cache.use_query_cache" value="true"/>
<property name="hibernate.generate_statistics" value="true"/>
<property name="hibernate.cache.provider_configuration_file_resource_path" value="ehcache.xml"/> 

ehcache.xml

<ehcache>
<diskStore path="c:\\web\\"/>
<defaultCache
maxElementsInMemory="10000"
eternal="false"
timeToIdleSeconds="360000"
timeToLiveSeconds="360000"
overflowToDisk="true"
diskPersistent="false"
diskExpiryThreadIntervalSeconds="360000"
memoryStoreEvictionPolicy="LRU"
/>

<cache name="org.hibernate.cache.UpdateTimestampsCache"
maxElementsInMemory="1000"
eternal="false"
timeToIdleSeconds="360000"
timeToLiveSeconds="360000"
overflowToDisk="true"
diskPersistent="false"
diskExpiryThreadIntervalSeconds="360000"
memoryStoreEvictionPolicy="LRU"
/>

<cache name="org.hibernate.cache.StandardQueryCache"
maxElementsInMemory="1000"
eternal="false"
timeToIdleSeconds="360000"
timeToLiveSeconds="360000"
overflowToDisk="true"
diskPersistent="false"
diskExpiryThreadIntervalSeconds="360000"
memoryStoreEvictionPolicy="LRU"
/>

<cache name="query.AdministrativeAreasPerCountry"
maxElementsInMemory="500"
eternal="false"
timeToIdleSeconds="180000"
timeToLiveSeconds="864000"
overflowToDisk="true"/>
</ehcache>

OptionDAO:

Query query = getEntityManager().createNamedQuery("from Option where uniqueOptionKey.lineOfBusinessId= :lineOfBusiness AND optionStatus= :stateCode)";
query.setParameter(LINE_OF_BUSINESS, lineOfBusiness);
query.setParameter(STATE_CODE, stateCode);

options = (List<Option>) query.getResultList();

Could you please provide some information on the same. where exactly is missing the configuration properties being set are overridden to the default. The query being cached for 2 minutes is the default time to cache.

Thanks and Regards,
Jayadev S