Hi,

I have an application that handles user data entry in a wizard-like process. Users submit a profile through a series of related pages. The database was created with a series of tables, each relating to a data-entry page. The tables are really related to the primary table in one-to-one relationships, and could be refactored into a single table with hundreds of columns. Since there is a legacy application that runs against the database, a significant modification to the schema in not really possible at this time.

Now for my problem:

I have implemented a Hibernate mapping that uses one-to-one relationships. The primary table (COMPANY) has multiple one-to-one relationships with other tables. When I do a query on the COMPANY table, Hibernate returns more entries than I have in the COMPANY table. I believe I tracked this down to the one-to-one relationships, and how Hibernate handles them. It appears to generate multiple SQL statements, each with a different left join order:

select ... from c_profile this left outer join company company1_ on this.companyid=company1_.companyid left outer join benefits benefits2_ on company1_.companyid=benefits2_.companyid where 1=1

select ... from company this left outer join c_profile cprofile1_ on this.companyid=cprofile1_.companyid left outer join benefits benefits2_ on this.companyid=benefits2_.companyid where 1=1

Both SQL statements above were generated as a result of the following single query:

Criteria crit = session.createCriteria(Company.class);

List l = crit.list();

The relevent portions of the mapping documents are listed below:

Company.hbm.xml:



Benefit.hbm.xml:


CProfile.hbm.xml:



Note that the Company.hbm has commented out many-to-one associations. I tried this based on the advice in HIA. It didn't change the result. I also tried different variations of constrained, cascade and unique, all with the same results.

Is there any way for me to map these associations correctly? Or do I have to consolidate all of the satelite tables into a single large table?

I am using Hibernate 2.1.6 against a MySQL database.

Thanks!

I thought that the problem was with having two one-to-one mappings in a single file, but that doesn't seem to be it. I commented out the one-to-one mappings in the Company.hbm.xml document, and still had issues. Then I commented out the mappings in the Benefits.hbm.xml and CProfile.hbm.xml documents, and still had issues. Finally, I commented out the mapping file entries in Hibernate.hbm.xml, and no longer had any issues. When I uncommented Benefits.hbml.xml, I still had no problems. But when I uncommented CProfile.hbm.xml, the problems reappeared, even when I commented Benefits.hbm.xml back out.

All that to say that the problem appears to be in CProfile.hbm.xml.

Here's what I've observed:

1. Whenever the path to CProfile.hbm.xml is commented out of hibernate.hbm.xml, I have no problems.
2. Whenever the path to CProfile.hbm.xml is NOT commented out of (included in) hibernate.hbm.xml, I have problems.
3. The problems occur even when I have no associations defined between Company.hbm.xml and CProfile.hbm.xml.
4. The problem can occur when I use Criteria or HQL queries, with or without additional criteria (listing everything from the table or adding restrictions).

This all happens when I query just for the Company object - I am not asking for the CProfile object at all, nor is subsequent code accessing it.

I can't for the life of me determine why the inclusion of the path to the CProfile.hbm.xml document causes problems, especially when all associations between that and Company have been broken. For a while, I thought I might have an issue with my IDE (Eclipse, using Maven). But I tested that by adding garbage to the CProfile.hbm.xml and Company.hbm.xml documents, one at a time, and Hibernate immediately threw document parsing errors. So I know Hibernate is working from the same documents I am.

The Benefits.hbm.xml and CProfile.hbm.xml documents have the exact same association mapping, and I've copied the association portion of the mapping document from Benefits to CProfile and vice-versa, and CProfile consistently causes problems and Benefits does not.

I'm attaching all three relevent mapping documents below.

Its damn hard to help you when you don't show:

(1) the actual query
(2) a Hibernate log

but at a guess, I would say try polymorphism="explicit" and see what happens.

My original post had the queries, but I truncated the column names being selected for readability. I'm posting them again in unabridged format below, along with the logs when configured for DEBUG settings:

[code]


2005-01-27 08:41:04,882 [main] DEBUG com.greenbuildingpages.dao.CompanyDAOTest - about to retrieve all companies.
2005-01-27 08:41:04,942 [main] INFO net.sf.hibernate.cfg.Environment - Hibernate 2.1.6
2005-01-27 08:41:04,942 [main] INFO net.sf.hibernate.cfg.Environment - hibernate.properties not found
2005-01-27 08:41:04,942 [main] INFO net.sf.hibernate.cfg.Environment - using CGLIB reflection optimizer
2005-01-27 08:41:04,952 [main] INFO net.sf.hibernate.cfg.Configuration - configuring from resource: /hibernate.cfg.xml
2005-01-27 08:41:04,952 [main] INFO net.sf.hibernate.cfg.Configuration - Configuration resource: /hibernate.cfg.xml
2005-01-27 08:41:05,393 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-configuration-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:05,403 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-configuration-2.0.dtd in classpath
2005-01-27 08:41:05,453 [main] DEBUG net.sf.hibernate.cfg.Configuration - connection.username=user
2005-01-27 08:41:05,453 [main] DEBUG net.sf.hibernate.cfg.Configuration - connection.url=jdbc:mysql://host.com:3306/user
2005-01-27 08:41:05,453 [main] DEBUG net.sf.hibernate.cfg.Configuration - dialect=net.sf.hibernate.dialect.MySQLDialect
2005-01-27 08:41:05,453 [main] DEBUG net.sf.hibernate.cfg.Configuration - connection.password=********
2005-01-27 08:41:05,453 [main] DEBUG net.sf.hibernate.cfg.Configuration - connection.driver_class=com.mysql.jdbc.Driver
2005-01-27 08:41:05,463 [main] DEBUG net.sf.hibernate.cfg.Configuration - show_sql=true
2005-01-27 08:41:05,463 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@c7b00c [Attribute: name resource value "com/greenbuildingpages/model/Company.hbm.xml"]
2005-01-27 08:41:05,463 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/Company.hbm.xml
2005-01-27 08:41:05,473 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:05,473 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:05,633 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.Company -> company
2005-01-27 08:41:05,723 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: companyid -> companyid, type: integer
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: name -> name, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: parent -> parent, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: address1 -> address1, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: address2 -> address2, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: city -> city, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: state -> state, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: zip -> zip, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: country -> country, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: tel -> tel, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: fax -> fax, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: email -> email, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: pubname -> pubname, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: pubaddress1 -> pubaddress1, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: pubaddress2 -> pubaddress2, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: pubcity -> pubcity, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: pubstate -> pubstate, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: pubzip -> pubzip, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: pubcountry -> pubcountry, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: url -> url, type: string
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: submit -> submit, type: integer
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: osha -> osha, type: integer
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: env -> env, type: integer
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: benefits -> benefits, type: integer
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: charity -> charity, type: integer
2005-01-27 08:41:05,743 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: cocPerc -> coc_perc, type: integer
2005-01-27 08:41:05,763 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: products, type: java.util.Set
2005-01-27 08:41:05,773 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: users, type: java.util.Set
2005-01-27 08:41:05,783 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: benefitProfile, type: com.greenbuildingpages.model.Benefits
2005-01-27 08:41:05,783 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: diversityProfile, type: com.greenbuildingpages.model.Diversity
2005-01-27 08:41:05,783 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@9e29fb [Attribute: name resource value "com/greenbuildingpages/model/Input.hbm.xml"]
2005-01-27 08:41:05,783 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/Input.hbm.xml
2005-01-27 08:41:05,793 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:05,793 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:05,823 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.Input -> input
2005-01-27 08:41:05,823 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: productid -> productid, type: integer
2005-01-27 08:41:05,823 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: reduce -> reduce, type: integer
2005-01-27 08:41:05,823 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: toxic -> toxic, type: integer
2005-01-27 08:41:05,823 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: description -> description, type: string
2005-01-27 08:41:05,823 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: complete -> complete, type: integer
2005-01-27 08:41:05,823 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: product, type: com.greenbuildingpages.model.Product
2005-01-27 08:41:05,823 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@e6ff0d [Attribute: name resource value "com/greenbuildingpages/model/Imat.hbm.xml"]
2005-01-27 08:41:05,823 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/Imat.hbm.xml
2005-01-27 08:41:05,833 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:05,833 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:05,873 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.Imat -> imat
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: id -> id, type: integer
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: productid -> productid, type: integer
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: name -> name, type: string
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: weight -> weight, type: integer
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: miles -> miles, type: integer
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: acquire -> acquire, type: string
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: consumer -> consumer, type: integer
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: industrial -> industrial, type: integer
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: renew -> renew, type: integer
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: toxic -> toxic, type: integer
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: product -> productid, type: com.greenbuildingpages.model.Product
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@90cb03 [Attribute: name resource value "com/greenbuildingpages/model/Diversity.hbm.xml"]
2005-01-27 08:41:05,873 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/Diversity.hbm.xml
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:05,873 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:05,903 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.Diversity -> diversity
2005-01-27 08:41:05,903 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: companyid -> companyid, type: integer
2005-01-27 08:41:05,903 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: divPol -> div_pol, type: integer
2005-01-27 08:41:05,903 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: divPolDesc -> div_pol_desc, type: string
2005-01-27 08:41:05,903 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: divGoal -> div_goal, type: integer
2005-01-27 08:41:05,903 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: divGoalDesc -> div_goal_desc, type: string
2005-01-27 08:41:05,903 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: divProg -> div_prog, type: integer
2005-01-27 08:41:05,913 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: divCon -> div_con, type: string
2005-01-27 08:41:05,913 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@d81784 [Attribute: name resource value "com/greenbuildingpages/model/Benefits.hbm.xml"]
2005-01-27 08:41:05,913 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/Benefits.hbm.xml
2005-01-27 08:41:05,913 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:05,913 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:05,944 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.Benefits -> benefits
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: companyid -> companyid, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: hins -> hins, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: retire -> retire, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: matPat -> mat_pat, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: sabb -> sabb, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: childcare -> childcare, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: edBen -> ed_ben, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: stock -> stock, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: jobShare -> job_share, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: transport -> transport, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: severance -> severance, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: skills -> skills, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: retrain -> retrain, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: fitness -> fitness, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: flexHours -> flex_hours, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: vac -> vac, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: sick -> sick, type: integer
2005-01-27 08:41:05,944 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: other -> other, type: string
2005-01-27 08:41:05,954 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: company, type: com.greenbuildingpages.model.Company
2005-01-27 08:41:05,954 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@139b78e [Attribute: name resource value "com/greenbuildingpages/model/User.hbm.xml"]
2005-01-27 08:41:05,954 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/User.hbm.xml
2005-01-27 08:41:05,954 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:05,954 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:05,994 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.User -> user
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: idnum -> idnum, type: integer
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: userid -> userid, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: pass -> pass, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: lastaccess -> lastaccess, type: timestamp
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: userfirst -> userfirst, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: userlast -> userlast, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: email -> email, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: security -> security, type: integer
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: challQ -> chall_q, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: challA -> chall_a, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: env -> env, type: integer
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: hr -> hr, type: integer
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: tel -> tel, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: ext -> ext, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: address1 -> address1, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: address2 -> address2, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: city -> city, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: state -> state, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: zip -> zip, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: country -> country, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: fax -> fax, type: string
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: company -> companyid, type: com.greenbuildingpages.model.Company
2005-01-27 08:41:05,994 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@b8deef [Attribute: name resource value "com/greenbuildingpages/model/Product.hbm.xml"]
2005-01-27 08:41:05,994 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/Product.hbm.xml
2005-01-27 08:41:06,004 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:06,004 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:06,024 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.Product -> product
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: productid -> productid, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: lastmoddate -> lastmoddate, type: timestamp
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: createddate -> createddate, type: timestamp
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: idxview -> idxview, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: detview -> detview, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: recentidx -> recentidx, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: recentdet -> recentdet, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: statdate -> statdate, type: timestamp
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: name -> name, type: string
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: prodfam -> prodfam, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: refer -> refer, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: recentrefer -> recentrefer, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: stat -> stat, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: viewDate -> view_date, type: timestamp
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: leed -> leed, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: io -> io, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: csi -> csi, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: type -> type, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: description -> description, type: string
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: url -> url, type: string
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: prop -> prop, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: gas -> gas, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: cocPerc -> coc_perc, type: integer
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: imageLoc -> image_loc, type: string
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: company -> companyid, type: com.greenbuildingpages.model.Company
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: input, type: com.greenbuildingpages.model.Input
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: inputMaterials, type: java.util.Set
2005-01-27 08:41:06,024 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@ef2c60 [Attribute: name resource value "com/greenbuildingpages/model/CProfile.hbm.xml"]
2005-01-27 08:41:06,024 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/CProfile.hbm.xml
2005-01-27 08:41:06,034 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:06,034 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:06,044 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.CProfile -> c_profile
2005-01-27 08:41:06,044 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: companyid -> companyid, type: integer
2005-01-27 08:41:06,044 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getOsha -> get_osha, type: integer
2005-01-27 08:41:06,044 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getEnvironment -> get_environment, type: integer
2005-01-27 08:41:06,044 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getBenefits -> get_benefits, type: integer
2005-01-27 08:41:06,044 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getWage -> get_wage, type: integer
2005-01-27 08:41:06,044 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getGender -> get_gender, type: integer
2005-01-27 08:41:06,044 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getDiversity -> get_diversity, type: integer
2005-01-27 08:41:06,044 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getCharity -> get_charity, type: integer
2005-01-27 08:41:06,044 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getSocialcoc -> get_socialcoc, type: integer
2005-01-27 08:41:06,054 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getCGoals -> get_c_goals, type: integer
2005-01-27 08:41:06,054 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getCCerts -> get_c_certs, type: integer
2005-01-27 08:41:06,054 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: company, type: com.greenbuildingpages.model.Company
2005-01-27 08:41:06,054 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@29ab3e [Attribute: name resource value "com/greenbuildingpages/model/Environment.hbm.xml"]
2005-01-27 08:41:06,054 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/Environment.hbm.xml
2005-01-27 08:41:06,054 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:06,054 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:06,074 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.Environment -> environment
2005-01-27 08:41:06,074 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: companyid -> companyid, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: polProd -> pol_prod, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: polEnd -> pol_end, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: polInst -> pol_inst, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: polSocial -> pol_social, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: goalProd -> goal_prod, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: goalEnd -> goal_end, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: goalInst -> goal_inst, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: goalSocial -> goal_social, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: goalNone -> goal_none, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: sponsor -> sponsor, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: train -> train, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: envGoalDesc -> env_goal_desc, type: string
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: envGoalCon -> env_goal_con, type: string
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: trainDesc -> train_desc, type: string
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: polNone -> pol_none, type: integer
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.cfg.Configuration - null<-org.dom4j.tree.DefaultAttribute@b01d43 [Attribute: name resource value "com/greenbuildingpages/model/PProfile.hbm.xml"]
2005-01-27 08:41:06,084 [main] INFO net.sf.hibernate.cfg.Configuration - Mapping resource: com/greenbuildingpages/model/PProfile.hbm.xml
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net/sf/hibernate/
2005-01-27 08:41:06,084 [main] DEBUG net.sf.hibernate.util.DTDEntityResolver - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
2005-01-27 08:41:06,104 [main] INFO net.sf.hibernate.cfg.Binder - Mapping class: com.greenbuildingpages.model.PProfile -> p_profile
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: productid -> productid, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getInput -> get_input, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getOutput -> get_output, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getH2o -> get_h2o, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getEnergy -> get_energy, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getGhgas -> get_ghgas, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getOzone -> get_ozone, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getPack -> get_pack, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getDist -> get_dist, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getProdcoc -> get_prodcoc, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: inst -> inst, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getOffgas -> get_offgas, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getDurability -> get_durability, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getDisposal -> get_disposal, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getCerts -> get_certs, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: leed -> leed, type: integer
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: notifyComplete -> notify_complete, type: timestamp
2005-01-27 08:41:06,104 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped property: getUenergy -> get_uenergy, type: integer
2005-01-27 08:41:06,104 [main] INFO net.sf.hibernate.cfg.Configuration - Configured SessionFactory: null
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Configuration - properties: {hibernate.connection.password=********, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=C:\j2sdk1.4.2_05\jre\bin, java.vm.version=1.4.2_05-b04, hibernate.connection.username=user, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=US, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\eclipse\workspace\GBP-Domain, java.runtime.version=1.4.2_05-b04, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\j2sdk1.4.2_05\jre\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\Todd\LOCALS~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, sun.java2d.fontpath=, java.library.path=C:\j2sdk1.4.2_05\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\PROGRA~1\Borland\CBUILD~1\Bin;C:\PROGRA~1\Borland\CBUILD~1\Projects\Bpl;C:\Perl\bin\;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\j2sdk1.4.2_05\bin;C:\Documents and Settings\Todd\My Documents;C:\j2sdkee1.3.1\bin;C:\ant\bin;C:\Maven\bin;C:\Program Files\Subversion\bin;C:\PROGRA~1\ATT\Graphviz\bin;C:\PROGRA~1\ATT\Graphviz\bin\tools;C:\Sun\jwsdp-1.5\jwsdp-shared\bin;C:\Sun\AppServer\bin;c:\cygnus\CYGWIN~1\H-I586~1\bin;C:\Handspring\prc-tools\bin;;C:\Program Files\SSH Communications Security\SSH Secure Shell;C:\j2sdk1.4.2_05\bin;C:\JBuilder8\jdk1.4\bin;C:\Documents and Settings\Todd\My Documents;C:\Sun\AppServer\bin;C:\Sun\AppServer\jdk\bin, java.specification.name=Java Platform API Specification, java.class.version=48.0, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, os.version=5.1, connection.password=********, user.home=C:\Documents and Settings\Todd, user.timezone=America/Los_Angeles, connection.username=user, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.driver_class=com.mysql.jdbc.Driver, show_sql=true, user.name=Todd, java.class.path=/c:/eclipse/plugins/org.eclipse.jdt.junit_3.0.1/junitsupport.jar;/c:/eclipse/plugins/org.eclipse.jdt.junit.runtime_3.0.0/junitruntime.jar;C:\eclipse\workspace\GBP-Domain\target\test-classes;C:\eclipse\workspace\GBP-Domain\target\classes;C:\Documents and Settings\Todd\.maven\repository\junit\jars\junit-3.8.jar;C:\Documents and Settings\Todd\.maven\repository\commons-collections\jars\commons-collections-3.1.jar;C:\Documents and Settings\Todd\.maven\repository\commons-dbcp\jars\commons-dbcp-1.2.1.jar;C:\Documents and Settings\Todd\.maven\repository\commons-digester\jars\commons-digester-1.6.jar;C:\Documents and Settings\Todd\.maven\repository\commons-pool\jars\commons-pool-1.2.jar;C:\Documents and Settings\Todd\.maven\repository\ehcache\jars\ehcache-0.9.jar;C:\Documents and Settings\Todd\.maven\repository\hibernate\jars\hibernate-2.1.6.jar;C:\Documents and Settings\Todd\.maven\repository\jta\jars\jta.jar;C:\Documents and Settings\Todd\.maven\repository\c3p0\jars\c3p0-0.8.4.5.jar;C:\Documents and Settings\Todd\.maven\repository\log4j\jars\log4j-1.2.8.jar;C:\Documents and Settings\Todd\.maven\repository\odmg\jars\odmg-3.0.jar;C:\Documents and Settings\Todd\.maven\repository\xalan\jars\xalan-2.4.0.jar;C:\Documents and Settings\Todd\.maven\repository\xerces\jars\xerces-2.4.0.jar;C:\Documents and Settings\Todd\.maven\repository\dom4j\jars\dom4j-1.4.jar;C:\Documents and Settings\Todd\.maven\repository\ant\jars\ant-1.6.2.jar;C:\Documents and Settings\Todd\.maven\repository\commons-logging\jars\commons-logging-1.0.4.jar;C:\Documents and Settings\Todd\.maven\repository\struts\jars\struts-1.2.4.jar;C:\Documents and Settings\Todd\.maven\repository\commons-beanutils\jars\commons-beanutils-1.7.0.jar;C:\Documents and Settings\Todd\.maven\repository\commons-lang\jars\commons-lang-2.0.jar;C:\Documents and Settings\Todd\.maven\repository\jstl\jars\jstl-1.1.2.jar;C:\Documents and Settings\Todd\.maven\repository\cglib\jars\cglib-full-2.0.jar;C:\Documents and Settings\Todd\.maven\repository\mysql\jars\mysql-1.5a.jar, hibernate.show_sql=true, java.vm.specification.version=1.0, java.home=C:\j2sdk1.4.2_05\jre, sun.arch.data.model=32, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, hibernate.connection.url=jdbc:mysql://host.com:3306/user, user.language=en, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, hibernate.cglib.use_reflection_optimizer=true, java.vm.info=mixed mode, java.version=1.4.2_05, java.ext.dirs=C:\j2sdk1.4.2_05\jre\lib\ext, sun.boot.class.path=C:\j2sdk1.4.2_05\jre\lib\rt.jar;C:\j2sdk1.4.2_05\jre\lib\i18n.jar;C:\j2sdk1.4.2_05\jre\lib\sunrsasign.jar;C:\j2sdk1.4.2_05\jre\lib\jsse.jar;C:\j2sdk1.4.2_05\jre\lib\jce.jar;C:\j2sdk1.4.2_05\jre\lib\charsets.jar;C:\j2sdk1.4.2_05\jre\classes, java.vendor=Sun Microsystems Inc., connection.driver_class=com.mysql.jdbc.Driver, file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, connection.url=jdbc:mysql://host.com:3306/user, dialect=net.sf.hibernate.dialect.MySQLDialect, sun.cpu.isalist=pentium i486 i386}
2005-01-27 08:41:06,114 [main] INFO net.sf.hibernate.cfg.Configuration - processing one-to-many association mappings
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Binder - Second pass for collection: com.greenbuildingpages.model.Company.products
2005-01-27 08:41:06,114 [main] INFO net.sf.hibernate.cfg.Binder - Mapping collection: com.greenbuildingpages.model.Company.products -> product
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped collection key: companyid, one-to-many: com.greenbuildingpages.model.Product
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Binder - Second pass for collection: com.greenbuildingpages.model.Company.users
2005-01-27 08:41:06,114 [main] INFO net.sf.hibernate.cfg.Binder - Mapping collection: com.greenbuildingpages.model.Company.users -> user
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped collection key: companyid, one-to-many: com.greenbuildingpages.model.User
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Binder - Second pass for collection: com.greenbuildingpages.model.Product.inputMaterials
2005-01-27 08:41:06,114 [main] INFO net.sf.hibernate.cfg.Binder - Mapping collection: com.greenbuildingpages.model.Product.inputMaterials -> imat
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Binder - Mapped collection key: productid, one-to-many: com.greenbuildingpages.model.Imat
2005-01-27 08:41:06,114 [main] INFO net.sf.hibernate.cfg.Configuration - processing one-to-one association property references
2005-01-27 08:41:06,114 [main] INFO net.sf.hibernate.cfg.Configuration - processing foreign key constraints
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Configuration - resolving reference to class: com.greenbuildingpages.model.Company
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Configuration - resolving reference to class: com.greenbuildingpages.model.Company
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Configuration - resolving reference to class: com.greenbuildingpages.model.Product
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Configuration - resolving reference to class: com.greenbuildingpages.model.Company
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Configuration - resolving reference to class: com.greenbuildingpages.model.Product
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Configuration - resolving reference to class: com.greenbuildingpages.model.Company
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Configuration - resolving reference to class: com.greenbuildingpages.model.Diversity
2005-01-27 08:41:06,114 [main] DEBUG net.sf.hibernate.cfg.Configuration - resolving reference to class: com.greenbuildingpages.model.Benefits
2005-01-27 08:41:06,144 [main] INFO net.sf.hibernate.dialect.Dialect - Using dialect: net.sf.hibernate.dialect.MySQLDialect
2005-01-27 08:41:06,154 [main] INFO net.sf.hibernate.cfg.SettingsFactory - Maximim outer join fetch depth: 2
2005-01-27 08:41:06,154 [main] INFO net.sf.hibernate.cfg.SettingsFactory - Use outer join fetching: true
2005-01-27 08:41:06,154 [main] INFO net.sf.hibernate.connection.DriverManagerConnectionProvider - Using Hibernate built-in connection pool (not for production use!)
2005-01-27 08:41:06,154 [main] INFO net.sf.hibernate.connection.DriverManagerConnectionProvider - Hibernate connection pool size: 20
2005-01-27 08:41:06,164 [main] INFO net.sf.hibernate.connection.DriverManagerConnectionProvider - using driver: com.mysql.jdbc.Driver at URL: jdbc:mysql://host.com:3306/user
2005-01-27 08:41:06,164 [main] INFO net.sf.hibernate.connection.DriverManagerConnectionProvider - connection properties: {user=user, password=********}
2005-01-27 08:41:06,174 [main] INFO net.sf.hibernate.transaction.TransactionManagerLookupFactory - No TransactionManagerLookup configured (in JTA environment, use of process level read-write cache is not recommended)
2005-01-27 08:41:06,174 [main] DEBUG net.sf.hibernate.connection.DriverManagerConnectionProvider - total checked-out connections: 0
2005-01-27 08:41:06,174 [main] DEBUG net.sf.hibernate.connection.DriverManagerConnectionProvider - opening new JDBC connection
2005-01-27 08:41:06,965 [main] DEBUG net.sf.hibernate.connection.DriverManagerConnectionProvider - created connection to: jdbc:mysql://host.com:3306/user, Isolation Level: 4
2005-01-27 08:41:06,965 [main] DEBUG net.sf.hibernate.connection.DriverManagerConnectionProvider - returning connection to pool, pool size: 1
2005-01-27 08:41:06,965 [main] INFO net.sf.hibernate.cfg.SettingsFactory - Use scrollable result sets: true
2005-01-27 08:41:06,965 [main] INFO net.sf.hibernate.cfg.SettingsFactory - Use JDBC3 getGeneratedKeys(): true
2005-01-27 08:41:06,965 [main] INFO net.sf.hibernate.cfg.SettingsFactory - Optimize cache for minimal puts: false
2005-01-27 08:41:06,965 [main] INFO net.sf.hibernate.cfg.SettingsFactory - echoing all SQL to stdout
2005-01-27 08:41:06,965 [main] INFO net.sf.hibernate.cfg.SettingsFactory - Query language substitutions: {}
2005-01-27 08:41:06,965 [main] INFO net.sf.hibernate.cfg.SettingsFactory - cache provider: net.sf.hibernate.cache.EhCacheProvider
2005-01-27 08:41:06,965 [main] INFO net.sf.hibernate.cfg.Configuration - instantiating and configuring caches
2005-01-27 08:41:07,105 [main] INFO net.sf.hibernate.impl.SessionFactoryImpl - building session factory
2005-01-27 08:41:07,105 [main] DEBUG net.sf.hibernate.impl.SessionFactoryImpl - instantiating session factory with properties: {hibernate.connection.password=********, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=C:\j2sdk1.4.2_05\jre\bin, java.vm.version=1.4.2_05-b04, hibernate.connection.username=user, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=;, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=US, sun.os.patch.level=Service Pack 2, java.vm.specification.name=Java Virtual Machine Specification, user.dir=C:\eclipse\workspace\GBP-Domain, java.runtime.version=1.4.2_05-b04, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.endorsed.dirs=C:\j2sdk1.4.2_05\jre\lib\endorsed, os.arch=x86, java.io.tmpdir=C:\DOCUME~1\Todd\LOCALS~1\Temp\, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., user.variant=, os.name=Windows XP, sun.java2d.fontpath=, java.library.path=C:\j2sdk1.4.2_05\bin;.;C:\WINDOWS\system32;C:\WINDOWS;C:\PROGRA~1\Borland\CBUILD~1\Bin;C:\PROGRA~1\Borland\CBUILD~1\Projects\Bpl;C:\Perl\bin\;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\j2sdk1.4.2_05\bin;C:\Documents and Settings\Todd\My Documents;C:\j2sdkee1.3.1\bin;C:\ant\bin;C:\Maven\bin;C:\Program Files\Subversion\bin;C:\PROGRA~1\ATT\Graphviz\bin;C:\PROGRA~1\ATT\Graphviz\bin\tools;C:\Sun\jwsdp-1.5\jwsdp-shared\bin;C:\Sun\AppServer\bin;c:\cygnus\CYGWIN~1\H-I586~1\bin;C:\Handspring\prc-tools\bin;;C:\Program Files\SSH Communications Security\SSH Secure Shell;C:\j2sdk1.4.2_05\bin;C:\JBuilder8\jdk1.4\bin;C:\Documents and Settings\Todd\My Documents;C:\Sun\AppServer\bin;C:\Sun\AppServer\jdk\bin, java.specification.name=Java Platform API Specification, java.class.version=48.0, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, os.version=5.1, connection.password=********, user.home=C:\Documents and Settings\Todd, user.timezone=America/Los_Angeles, connection.username=user, java.awt.printerjob=sun.awt.windows.WPrinterJob, file.encoding=Cp1252, java.specification.version=1.4, hibernate.connection.driver_class=com.mysql.jdbc.Driver, show_sql=true, user.name=Todd, java.class.path=/c:/eclipse/plugins/org.eclipse.jdt.junit_3.0.1/junitsupport.jar;/c:/eclipse/plugins/org.eclipse.jdt.junit.runtime_3.0.0/junitruntime.jar;C:\eclipse\workspace\GBP-Domain\target\test-classes;C:\eclipse\workspace\GBP-Domain\target\classes;C:\Documents and Settings\Todd\.maven\repository\junit\jars\junit-3.8.jar;C:\Documents and Settings\Todd\.maven\repository\commons-collections\jars\commons-collections-3.1.jar;C:\Documents and Settings\Todd\.maven\repository\commons-dbcp\jars\commons-dbcp-1.2.1.jar;C:\Documents and Settings\Todd\.maven\repository\commons-digester\jars\commons-digester-1.6.jar;C:\Documents and Settings\Todd\.maven\repository\commons-pool\jars\commons-pool-1.2.jar;C:\Documents and Settings\Todd\.maven\repository\ehcache\jars\ehcache-0.9.jar;C:\Documents and Settings\Todd\.maven\repository\hibernate\jars\hibernate-2.1.6.jar;C:\Documents and Settings\Todd\.maven\repository\jta\jars\jta.jar;C:\Documents and Settings\Todd\.maven\repository\c3p0\jars\c3p0-0.8.4.5.jar;C:\Documents and Settings\Todd\.maven\repository\log4j\jars\log4j-1.2.8.jar;C:\Documents and Settings\Todd\.maven\repository\odmg\jars\odmg-3.0.jar;C:\Documents and Settings\Todd\.maven\repository\xalan\jars\xalan-2.4.0.jar;C:\Documents and Settings\Todd\.maven\repository\xerces\jars\xerces-2.4.0.jar;C:\Documents and Settings\Todd\.maven\repository\dom4j\jars\dom4j-1.4.jar;C:\Documents and Settings\Todd\.maven\repository\ant\jars\ant-1.6.2.jar;C:\Documents and Settings\Todd\.maven\repository\commons-logging\jars\commons-logging-1.0.4.jar;C:\Documents and Settings\Todd\.maven\repository\struts\jars\struts-1.2.4.jar;C:\Documents and Settings\Todd\.maven\repository\commons-beanutils\jars\commons-beanutils-1.7.0.jar;C:\Documents and Settings\Todd\.maven\repository\commons-lang\jars\commons-lang-2.0.jar;C:\Documents and Settings\Todd\.maven\repository\jstl\jars\jstl-1.1.2.jar;C:\Documents and Settings\Todd\.maven\repository\cglib\jars\cglib-full-2.0.jar;C:\Documents and Settings\Todd\.maven\repository\mysql\jars\mysql-1.5a.jar, hibernate.show_sql=true, java.vm.specification.version=1.0, java.home=C:\j2sdk1.4.2_05\jre, sun.arch.data.model=32, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, hibernate.connection.url=jdbc:mysql://host.com:3306/user, user.language=en, java.specification.vendor=Sun Microsystems Inc., awt.toolkit=sun.awt.windows.WToolkit, hibernate.cglib.use_reflection_optimizer=true, java.vm.info=mixed mode, java.version=1.4.2_05, java.ext.dirs=C:\j2sdk1.4.2_05\jre\lib\ext, sun.boot.class.path=C:\j2sdk1.4.2_05\jre\lib\rt.jar;C:\j2sdk1.4.2_05\jre\lib\i18n.jar;C:\j2sdk1.4.2_05\jre\lib\sunrsasign.jar;C:\j2sdk1.4.2_05\jre\lib\jsse.jar;C:\j2sdk1.4.2_05\jre\lib\jce.jar;C:\j2sdk1.4.2_05\jre\lib\charsets.jar;C:\j2sdk1.4.2_05\jre\classes, java.vendor=Sun Microsystems Inc., connection.driver_class=com.mysql.jdbc.Driver, file.separator=\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, connection.url=jdbc:mysql://host.com:3306/user, dialect=net.sf.hibernate.dialect.MySQLDialect, sun.cpu.isalist=pentium i486 i386}
2005-01-27 08:41:07,846 [main] DEBUG net.sf.hibernate.impl.SessionFactoryObjectFactory - initializing class SessionFactoryObjectFactory
2005-01-27 08:41:07,856 [main] DEBUG net.sf.hibernate.impl.SessionFactoryObjectFactory - registered: 4028819001b50c240101b50c28060000 (unnamed)
2005-01-27 08:41:07,856 [main] INFO net.sf.hibernate.impl.SessionFactoryObjectFactory - Not binding factory to JNDI, no JNDI name configured
2005-01-27 08:41:07,856 [main] DEBUG net.sf.hibernate.impl.SessionFactoryImpl - instantiated session factory
2005-01-27 08:41:07,906 [main] DEBUG net.sf.hibernate.impl.SessionImpl - opened session
2005-01-27 08:41:07,926 [main] DEBUG net.sf.hibernate.impl.SessionImpl - flushing session
2005-01-27 08:41:07,926 [main] DEBUG net.sf.hibernate.impl.SessionImpl - Flushing entities and processing referenced collections
2005-01-27 08:41:07,926 [main] DEBUG net.sf.hibernate.impl.SessionImpl - Processing unreferenced collections
2005-01-27 08:41:07,926 [main] DEBUG net.sf.hibernate.impl.SessionImpl - Scheduling collection removes/(re)creates/updates
2005-01-27 08:41:07,926 [main] DEBUG net.sf.hibernate.impl.SessionImpl - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
2005-01-27 08:41:07,926 [main] DEBUG net.sf.hibernate.impl.SessionImpl - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
2005-01-27 08:41:07,936 [main] DEBUG net.sf.hibernate.impl.SessionImpl - Dont need to execute flush
2005-01-27 08:41:07,936 [main] DEBUG net.sf.hibernate.impl.BatcherImpl - about to open: 0 open PreparedStatements, 0 open ResultSets
2005-01-27 08:41:07,936 [main] DEBUG net.sf.hibernate.connection.DriverManagerConnectionProvider - total checked-out connections: 0
2005-01-27 08:41:07,936 [main] DEBUG net.sf.hibernate.connection.DriverManagerConnectionProvider - using pooled JDBC connection, pool size: 0
2005-01-27 08:41:07,936 [main] DEBUG net.sf.hibernate.SQL - select this.companyid as companyid2_, this.name as name2_, this.parent as parent2_, this.address1 as address12_, this.address2 as address22_, this.city as city2_, this.state as state2_, this.zip as zip2_, this.country as country2_, this.tel as tel2_, this.fax as fax2_, this.email as email2_, this.pubname as pubname2_, this.pubaddress1 as pubaddr14_2_, this.pubaddress2 as pubaddr15_2_, this.pubcity as pubcity2_, this.pubstate as pubstate2_, this.pubzip as pubzip2_, this.pubcountry as pubcountry2_, this.url as url2_, this.submit as submit2_, this.osha as osha2_, this.env as env2_, this.benefits as benefits2_, this.charity as charity2_, this.coc_perc as coc_perc2_, benefits1_.companyid as companyid0_, benefits1_.hins as hins0_, benefits1_.retire as retire0_, benefits1_.mat_pat as mat_pat0_, benefits1_.sabb as sabb0_, benefits1_.childcare as childcare0_, benefits1_.ed_ben as ed_ben0_, benefits1_.stock as stock0_, benefits1_.job_share as job_share0_, benefits1_.transport as transport0_, benefits1_.severance as severance0_, benefits1_.skills as skills0_, benefits1_.retrain as retrain0_, benefits1_.fitness as fitness0_, benefits1_.flex_hours as flex_hours0_, benefits1_.vac as vac0_, benefits1_.sick as sick0_, benefits1_.other as other0_, diversity2_.companyid as companyid1_, diversity2_.div_pol as div_pol1_, diversity2_.div_pol_desc as div_pol_3_1_, diversity2_.div_goal as div_goal1_, diversity2_.div_goal_desc as div_goal5_1_, diversity2_.div_prog as div_prog1_, diversity2_.div_con as div_con1_ from company this left outer join benefits benefits1_ on this.companyid=benefits1_.companyid left outer join diversity diversity2_ on this.companyid=diversity2_.companyid where this.companyid>=?
2005-01-27 08:41:07,936 [main] DEBUG net.sf.hibernate.impl.BatcherImpl - preparing statement
2005-01-27 08:41:07,956 [main] DEBUG net.sf.hibernate.type.IntegerType - binding '0' to parameter: 1
2005-01-27 08:41:08,397 [main] DEBUG net.sf.hibernate.loader.Loader - processing result set
2005-01-27 08:41:08,397 [main] DEBUG net.sf.hibernate.type.IntegerType - returning '1' as column: companyid0_
2005-01-27 08:41:08,397 [main] DEBUG net.sf.hibernate.type.IntegerType - returning '1' as column: companyid1_
2005-01-27 08:41:08,397 [main] DEBUG net.sf.hibernate.type.IntegerType - returning '1' as column: companyid2_
2005-01-27 08:41:08,397 [main] DEBUG net.sf.hibernate.loader.Loader - result row: 1, 1, 1
2005-01-27 08:41:08,397 [main] DEBUG net.sf.hibernate.loader.Loader - Initializing object from ResultSet: 1
2005-01-27 08:41:08,397 [main] DEBUG net.sf.hibernate.loader.Loader - Hydrating entity: com.greenbuildingpages.model.Benefits#1
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: hins0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: retire0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: mat_pat0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: sabb0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: childcare0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: ed_ben0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: stock0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: job_share0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: transport0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: severance0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: skills0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: retrain0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: fitness0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: flex_hours0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: vac0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: sick0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning null as column: other0_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.loader.Loader - Initializing object from ResultSet: 1
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.loader.Loader - Hydrating entity: com.greenbuildingpages.model.Diversity#1
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: div_pol1_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning null as column: div_pol_3_1_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: div_goal1_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning null as column: div_goal5_1_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.IntegerType - returning null as column: div_prog1_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning null as column: div_con1_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.loader.Loader - Initializing object from ResultSet: 1
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.loader.Loader - Hydrating entity: com.greenbuildingpages.model.Company#1
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'Green Building Pages' as column: name2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'Habitat Studios' as column: parent2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning '1350 Marsh Street' as column: address12_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'Suite 1' as column: address22_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'San Luis Obispo' as column: city2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'CA' as column: state2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning '93401' as column: zip2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'USA' as column: country2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning '(805)544-6075' as column: tel2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning '805-544-2494' as column: fax2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'marilyn@habitatdesigns.com' as column: email2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'Marilyn Farmer' as column: pubname2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning '1350 Marsh Street' as column: pubaddr14_2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning null as column: pubaddr15_2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'San Luis Obispo' as column: pubcity2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'CA' as column: pubstate2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning '93401' as column: pubzip2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'USA' as column: pubcountry2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type.StringType - returning 'http://www.greenbuildingpages.com' as column: url2_
2005-01-27 08:41:08,407 [main] DEBUG net.sf.hibernate.type

I finally remapped the table and generated the corresponding Java code from the DDL, and this solved my problem. Probably something I should have done a while back. Anyhow, I'll chalk it up to my IDE (MyEclipse Hibernate Tool) or my inexperience. I still can't identify the root cause of the problem.

The thing that finally got me to break down and remap the file was when I added a criteria to the query for non-null company names, and got a Hibernate error that the name parameter could not be resolved in the CProfile class.



Somehow, somewhere, it appears that Hibernate was confusing the Company and CProfile class. I don't know where, but it surely was either something I was doing wrong or was being injected by MyEclipse that I had no visibility to.

Gavin, thanks for taking time to look at this. I appreciate your time and expertise.