Need help with Hibernate? Read this first:
http://www.hibernate.org/ForumMailingli ... AskForHelp

Hibernate version:

Mapping documents:

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

Full stack trace of any exception that occurs:

Name and version of the database you are using:

The generated SQL (show_sql=true):

Debug level Hibernate log excerpt:

I'm getting a MethodInvocationException with HibernateTools when using hbm2hbmxml to generate HBMs from POJOs, as follows:

[hibernatetool] 14:51:20,333 ERROR template:94 - Method getJavaTypeName threw exception for reference $c2j in template hbm/many-to-one.hbm.vm at [3,20]

BUILD FAILED
F:\temp\build.xml:50: org.hibernate.tool.hbm2x.ExporterException: MethodInvocationException while processing template hbm/many-to-one.hbm.vm. Invocation of method 'getJavaTypeName' in class org.hibernate.tool.hbm2x.Cfg2JavaTool threw exception class java.lang.NullPointerException : null

The build does create two HBM files before the exception occurs. A third HBM file appears in the output folder, but it contains zero bytes when the exception occurs. The name of that HBM file is Movie.hbm.xml

I have used the POJOs together with the the Unisys RDMS OS2200 relational database to perform SELECTs and INSERTs successfully.

I'd appreciate any suggestions or help anyone may have to offer.

Environment:
Hibernate 3.1.0
Hibernate Tools in JBossIDE 1.5 Final Release (folder name is C:\eclipse\plugins\org.hibernate.eclipse_3.1.0.beta3\lib\tools)
JBoss 4.0.2 plus EJB 3 RC3
Eclipse 3.1.1
Windows XP Service Pack 2
ANT 1.6.5 (ant -debug output appears at the end of this message)
CLASSPATH (appears in the ant -debug output)

POJOs, hibernate.cfg.xml and ANT debug output and HBM files follow:

package com.demos.hibernatemovies;

import javax.persistence.Entity;
import javax.persistence.GeneratorType;
import javax.persistence.Id;
import javax.persistence.Table;
import javax.persistence.Column;
import javax.persistence.OneToMany;
import javax.persistence.JoinColumn;
import javax.persistence.FetchType;
import javax.persistence.CascadeType;
import javax.persistence.NamedQuery;

/*
@NamedQuery(
name="findAllStudiosWithName",
queryString="SELECT s.studioName FROM Studio s WHERE s.studioName = :studioName"
)
*/

import java.util.Set;
import java.util.HashSet;

import com.demos.hibernatemovies.Movie;

@Entity
@Table(name="STUDIO")
public class Studio implements java.io.Serializable {
private String studioName ;
private Set<Movie> movies = new HashSet<Movie>();

/** default constructor */
public Studio() {
}

/** minimal constructor */
public Studio(String studioName) {
this.studioName = studioName;
}

/** full constructor */
public Studio(String studioName, Set<Movie>movies) {
this.studioName = studioName;
this.movies = movies;
}

@Id(generate=GeneratorType.NONE)
@Column(name="STUDIO_NAME", nullable=false, length=64)
public String getStudioName() {
return this.studioName;
}
public void setStudioName(String studioName) {
this.studioName = studioName;
}

public void addMovie(Movie movie)
{
if (movies == null) movies = new HashSet<Movie>();
movies.add(movie);
System.out.println("Exit: addMovie()");
}

@OneToMany(targetEntity=com.demos.hibernatemovies.Movie.class,
mappedBy="studio",
cascade = CascadeType.ALL,
fetch = FetchType.EAGER )
public Set<Movie> getMovies()
{

return movies;
}

public void setMovies(Set<Movie> movies)
{
this.movies = movies;
}

}

package com.demos.hibernatemovies;

import javax.persistence.Entity;
import javax.persistence.GeneratorType;
import javax.persistence.Id;
import javax.persistence.Table;
import javax.persistence.Column;
import javax.persistence.ManyToOne;
import javax.persistence.JoinColumn;

import java.sql.Date;
import java.math.BigDecimal;
import java.util.HashSet;
import java.util.Set;
import java.util.Calendar;


import com.demos.hibernatemovies.Actor;
import com.demos.hibernatemovies.Category;
import javax.persistence.JoinTable;
import javax.persistence.ManyToMany;
import javax.persistence.CascadeType;
import javax.persistence.FetchType;


@Entity
@Table(name="MOVIE")
public class Movie implements java.io.Serializable {

private String movieTitle;
private Date releaseDate;
// private String studioName;
private Studio studio = new Studio();
private BigDecimal budget;
private Set<Actor> actors = new HashSet<Actor>();
private Set<Category> categories = new HashSet<Category>();

/** default constructor */
public Movie() {
}

/** full constructor */
public Movie(String movieTitle, Date releaseDate, Studio studio, BigDecimal budget, Set<Actor> actors, Set<Category> categories) {
this.movieTitle = movieTitle;
this.releaseDate = releaseDate;
// this.studioName = studioName;
this.studio = studio;
this.budget = budget;
this.actors = actors;
this.categories = categories;
}

/** minimal constructor */
public Movie(String movieTitle) {
this.movieTitle = movieTitle;
}

@Id(generate=GeneratorType.NONE)
@Column(name="MOVIE_TITLE", nullable=false, length=64)
public String getMovieTitle() {
return this.movieTitle;
}
public void setMovieTitle(String movieTitle) {
this.movieTitle = movieTitle;
}

@Column(name="REL_DATE", nullable=true)
public Date getReleaseDate() {
return this.releaseDate;
}
public void setReleaseDate(Date releaseDate) {
this.releaseDate = releaseDate;
}

@ManyToOne(optional=true,
targetEntity=com.demos.hibernatemovies.Studio.class,
fetch=FetchType.EAGER)
public Studio getStudio() {
return this.studio;
}
public void setStudio(Studio studio) {
this.studio = studio;
}



@Column(name="BUDGET", nullable=true, length=2)
public BigDecimal getBudget() {
return this.budget;
}
public void setBudget(BigDecimal budget) {
this.budget = budget;
}


@ManyToMany(
targetEntity=com.demos.hibernatemovies.Actor.class,
cascade={CascadeType.PERSIST, CascadeType.MERGE}
)
@JoinTable(
table=@Table(name="MOVIE_ACTORS"),
joinColumns={@JoinColumn(name="MOVIE_TITLE")},
inverseJoinColumns={@JoinColumn(name="FIRST_NAME"), @JoinColumn(name="LAST_NAME")}
)

public Set<Actor> getActors() {
return actors;
}

public void setActors(Set<Actor> actors)
{
this.actors = actors;
}

public void addActor(Actor actor)
{
if (actors == null) actors = new HashSet<Actor>();
actors.add(actor);
}

@ManyToMany(
targetEntity=com.demos.hibernatemovies.Category.class,
cascade={CascadeType.PERSIST, CascadeType.MERGE}
)
@JoinTable(
table=@Table(name="MOVIE_CATEGORIES"),
joinColumns={@JoinColumn(name="MOVIE_TITLE")},
inverseJoinColumns={@JoinColumn(name="CATEGORY")}
)
public Set<Category> getCategories() {
return categories;
}

public void setCategories(Set<Category> categories)
{
this.categories = categories;
}

public void addCategory(Category category) {
if (categories == null) categories = new HashSet<Category>();
categories.add(category);
}



public static Date parseReleaseDate(String year, String month, String day) {
Calendar cal = Calendar.getInstance();
cal.set(Calendar.YEAR, Integer.parseInt(year));
cal.set(Calendar.MONTH, Integer.parseInt(month));
cal.set(Calendar.DATE, Integer.parseInt(day));
cal.set(Calendar.HOUR, 0);
cal.set(Calendar.MINUTE, 0);
cal.set(Calendar.SECOND, 0);
cal.set(Calendar.MILLISECOND, 0);

long millis = cal.getTime().getTime();
java.sql.Date date = new Date(millis);
System.out.println("yyyymmdd=" + year + month + day
+ " "
+ "date="
+ date);
return date;
}

public int hashCode()
{
int result;
result = getMovieTitle().hashCode();
result = 29 * result;
return result;
}

public boolean equals(Object obj)
{
if (obj == this) return true;
if (!(obj instanceof Movie)) return false;
if (obj == null) return false;
Movie pk = (Movie) obj;
return pk.movieTitle.equals(movieTitle);
}

}

package com.demos.hibernatemovies;


import javax.persistence.CascadeType;
import javax.persistence.Entity;
import javax.persistence.ManyToMany;
import javax.persistence.Table;
import javax.persistence.Column;
import javax.persistence.GeneratorType;
import javax.persistence.Id;

import java.util.Set;
import java.util.HashSet;

import com.demos.hibernatemovies.Movie;


@Entity
@Table(name="CATEGORY")
public class Category implements java.io.Serializable {

private String category;
private String description;
private Set<Movie> movies = new HashSet<Movie>();

/** default constructor */
public Category() {
}

/** full constructor */
public Category(String category, String description, Set<Movie> movies) {
this.category = category;
this.description = description;
this.movies = movies;
}

/** minimal constructor */
public Category(String category) {
this.category = category;
}

@Id(generate=GeneratorType.NONE)
@Column(name="CATEGORY", nullable=false, length=64)
public String getCategory() {
return this.category;
}
public void setCategory(String category) {
this.category = category;
}

@Column(name="DESC", nullable=true, length=255)
public String getDescription() {
return this.description;
}
public void setDescription(String description) {
this.description = description;
}

@ManyToMany(
cascade={CascadeType.PERSIST, CascadeType.MERGE},
mappedBy="categories")
public Set<Movie> getMovies() {

return this.movies;
}

public void setMovies(Set<Movie> movies) {
this.movies = movies;
}
public void addMovie(Movie movie)
{
if (movies == null) movies = new HashSet<Movie>();
movies.add(movie);
}

}
package com.demos.hibernatemovies;


import javax.persistence.Embeddable;


@Embeddable
public class ActorPK implements java.io.Serializable {
private String firstName;
private String lastName;


public ActorPK()
{
}

public ActorPK(String firstName, String lastName)
{
this.firstName = firstName;
this.lastName = lastName;
}

public String getFirstName()
{
return firstName;
}

public void setFirstName(String firstName)
{
this.firstName = firstName;
}

public String getLastName()
{
return lastName;
}

public void setLastName(String lastName)
{
this.lastName = lastName;
}


public int hashCode()
{
String fullName = firstName + lastName;
return (int) 29*fullName.hashCode();
}

public boolean equals(Object obj)
{
if (obj == this) return true;
if (!(obj instanceof ActorPK)) return false;
if (obj == null) return false;
ActorPK pk = (ActorPK) obj;
return pk.firstName.equals(firstName) && pk.lastName.equals(lastName);
}

}
package com.demos.hibernatemovies;

import javax.persistence.Entity;
import javax.persistence.Column;
import javax.persistence.EmbeddedId;
import javax.persistence.AttributeOverrides;
import javax.persistence.AttributeOverride;
import javax.persistence.Table;
import javax.persistence.Transient;
import javax.persistence.CascadeType;
import javax.persistence.ManyToMany;

import com.demos.hibernatemovies.ActorPK;
import com.demos.hibernatemovies.Actor;
import com.demos.hibernatemovies.Movie;


import java.util.Set;
import java.util.HashSet;

@Entity
@Table(name="ACTOR")
public class Actor implements java.io.Serializable {


private ActorPK pk = new ActorPK();
private Set<Movie> movies = new HashSet<Movie>();

/** default constructor */
public Actor()
{
}


/** full constructor */
public Actor(String firstName, String lastName, Set<Movie> movies) {
this.pk.setFirstName(firstName);
this.pk.setLastName(lastName);
this.movies = movies;
}

/** minimal constructor */
public Actor(String firstName, String lastName) {
this.pk.setFirstName(firstName);
this.pk.setLastName(lastName);
}


@EmbeddedId
@AttributeOverrides({
@AttributeOverride(name = "firstName", column = @Column(name="FIRST_NAME", length=32) ),
@AttributeOverride(name = "lastName", column = @Column(name="LAST_NAME", length=32))
})
public ActorPK getPk()
{
return pk;
}
public void setPk(ActorPK pk)
{
this.pk = pk;
}

@Transient
public String getFirstName()
{
return pk.getFirstName();
}
@Transient
public String getLastName()
{
return pk.getLastName();
}

@ManyToMany(
cascade={CascadeType.PERSIST, CascadeType.MERGE},
mappedBy="actors")
public Set<Movie> getMovies(){
return this.movies;
}
public void setMovies(Set<Movie> movies){
if (movies == null) movies = new HashSet<Movie>();
this.movies = movies;
}

public void addMovie(Movie movie) {
if (movies == null) movies = new HashSet<Movie>();
movies.add(movie);
}

}


hibernate.cfg.xml:
<?xml version='1.0' encoding='utf-8'?>
<!DOCTYPE hibernate-configuration PUBLIC
"-//Hibernate/Hibernate Configuration DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-configuration-3.0.dtd">

<hibernate-configuration>

<session-factory>

<!-- Database connection settings -->
<property name="hibernate.connection.driver_class">com.unisys.os2200.rdms.jdbc.RdmsDriver</property>
<property name="hibernate.connection.url">jdbc:rdms:host=xxx.xxx.xxx.xxx;port=xxxx;schema=ANNOTATIONS1;varchar=varChar;</property>
<property name="hibernate.connection.username">xxxx</property>
<property name="hibernate.connection.password">xxxx</property>
<property name="hibernate.transaction.factory_class">org.hibernate.transaction.JDBCTransactionFactory</property>
<property name="hibernate.cache.provider_class">org.hibernate.cache.HashtableCacheProvider</property>
<property name="hibernate.dialect">org.hibernate.dialect.RDMSOS2200Dialect</property>
<property name="hibernate.default_schema">ANNOTATIONS1</property>
<property name="hibernate.connection.isolation">2</property>
<property name="hibernate.jdbc.use_scrollable_resultset">false</property>
<property name="hibernate.jdbc.use_streams_for_binary">false</property>
<property name="hibernate.jdbc.use_get_generated_keys">false</property>
<property name="hibernate.use_outer_join">true</property>
<property name="hibernate.fetch_size">10</property>
<property name="hibernate.max_fetch_depth">3</property>
<property name="hibernate.query.factory_class">org.hibernate.hql.ast.ASTQueryTranslatorFactory</property>
<property name="hibernate.query.substitutions">true 1, false 0</property>
<property name="hibernate.show_sql">true</property>


<!-- JDBC connection pool (use the built-in) -->
<property name="connection.pool_size">1</property>

<!-- Enable Hibernate's automatic session context management -->
<property name="current_session_context_class">thread</property>

<mapping package="com.demos.hibernatemovies"/>
<mapping class="com.demos.hibernatemovies.Actor"/>
<mapping class="com.demos.hibernatemovies.ActorPK"/>
<mapping class="com.demos.hibernatemovies.Category"/>
<mapping class="com.demos.hibernatemovies.Movie"/>
<mapping class="com.demos.hibernatemovies.Studio"/>
</session-factory>

</hibernate-configuration>






Buildfile: build.xml
[echo] Classpath is F:\Scratch-Hibernate-Tools-3-1-0-Beta2-Download\plugins\org.hibernate.eclipse_3.1.0.beta2\lib\tools\hibernate-tools.jar;F:\Scratch-Hibernate-Tools-3-1-0-Beta2-Download\plugins\org.hibernate.eclipse_3.1.0.beta2\lib\tools\jtidy-r8-21122004.jar;F:\Scratch-Hibernate-Tools-3-1-0-Beta2-Download\plugins\org.hibernate.eclipse_3.1.0.beta2\lib\tools\velocity-1.4.jar;F:\Scratch-Hibernate-Tools-3-1-0-Beta2-Download\plugins\org.hibernate.eclipse_3.1.0.beta2\lib\tools\velocity-tools-generic-1.1.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\antlr-2.7.6rc1.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\asm-attrs.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\asm.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\cglib-2.1.3.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\commons-collections-2.1.1.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\commons-logging-1.0.4.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\dom4j-1.6.1.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\ehcache-1.1.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\ejb3-persistence.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\hibernate-annotations.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\hibernate-entitymanager.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\hibernate3.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\jboss-common.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\jdbc2_0-stdext.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\jta.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\log4j-1.2.11.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\lib\xml-apis.jar;F:\JBoss-4-0-2-EJB3-RC3\jboss-4.0.2\server\all\deploy\jboss-aop-jdk50.deployer\javassist.jar;F:\JDBC-for-RDMS\udssvn\RS02\rdmsdriver.jar;F:\JDBC-for-RDMS\connector-api.jar;F:\MyEclipse-3-1-1-JBoss-IDE-1-5-Bundle-Final-Release-Projects\Annotations1-Hibernate-3-1\bin

fromAnnotations:
[hibernatetool] Executing Hibernate Tool with a Hibernate Annotation/EJB3 Configuration
[hibernatetool] 1. task: hbm2hbmxml (Generates a set of hbm.xml files)
[hibernatetool] 14:51:13,964 INFO Environment:479 - Hibernate 3.1
[hibernatetool] 14:51:14,034 INFO Environment:509 - hibernate.properties not found
[hibernatetool] 14:51:14,044 INFO Environment:525 - using CGLIB reflection optimizer
[hibernatetool] 14:51:14,054 INFO Environment:555 - using JDK 1.4 java.sql.Timestamp handling
[hibernatetool] 14:51:14,595 INFO Configuration:1320 - configuring from file: hibernate.cfg.xml
[hibernatetool] 14:51:14,855 DEBUG DTDEntityResolver:42 - trying to locate http://hibernate.sourceforge.net/hibern ... on-3.0.dtd in classpath under org/hibernate/
[hibernatetool] 14:51:14,865 DEBUG DTDEntityResolver:56 - found http://hibernate.sourceforge.net/hibern ... on-3.0.dtd in classpath
[hibernatetool] 14:51:15,055 DEBUG Configuration:1247 - hibernate.connection.driver_class=com.unisys.os2200.rdms.jdbc.RdmsDriver
[hibernatetool] 14:51:15,065 DEBUG Configuration:1247 - hibernate.connection.url=jdbc:rdms:host=xxx.xxx.xxx.xxx;port=xxxx;schema=ANNOTATIONS1;varchar=varChar;
[hibernatetool] 14:51:15,065 DEBUG Configuration:1247 - hibernate.connection.username=xxxxxx
[hibernatetool] 14:51:15,065 DEBUG Configuration:1247 - hibernate.connection.password=xxxxxx
[hibernatetool] 14:51:15,065 DEBUG Configuration:1247 - hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory
[hibernatetool] 14:51:15,065 DEBUG Configuration:1247 - hibernate.cache.provider_class=org.hibernate.cache.HashtableCacheProvider
[hibernatetool] 14:51:15,075 DEBUG Configuration:1247 - hibernate.dialect=org.hibernate.dialect.RDMSOS2200Dialect
[hibernatetool] 14:51:15,075 DEBUG Configuration:1247 - hibernate.default_schema=ANNOTATIONS1
[hibernatetool] 14:51:15,075 DEBUG Configuration:1247 - hibernate.connection.isolation=2
[hibernatetool] 14:51:15,085 DEBUG Configuration:1247 - hibernate.jdbc.use_scrollable_resultset=false
[hibernatetool] 14:51:15,085 DEBUG Configuration:1247 - hibernate.jdbc.use_streams_for_binary=false
[hibernatetool] 14:51:15,085 DEBUG Configuration:1247 - hibernate.jdbc.use_get_generated_keys=false
[hibernatetool] 14:51:15,085 DEBUG Configuration:1247 - hibernate.use_outer_join=true
[hibernatetool] 14:51:15,095 DEBUG Configuration:1247 - hibernate.fetch_size=10
[hibernatetool] 14:51:15,095 DEBUG Configuration:1247 - hibernate.max_fetch_depth=3
[hibernatetool] 14:51:15,095 DEBUG Configuration:1247 - hibernate.query.factory_class=org.hibernate.hql.ast.ASTQueryTranslatorFactory
[hibernatetool] 14:51:15,095 DEBUG Configuration:1247 - hibernate.query.substitutions=true 1, false 0
[hibernatetool] 14:51:15,105 DEBUG Configuration:1247 - hibernate.show_sql=true
[hibernatetool] 14:51:15,105 DEBUG Configuration:1247 - connection.pool_size=1
[hibernatetool] 14:51:15,105 DEBUG Configuration:1247 - current_session_context_class=thread
[hibernatetool] 14:51:15,105 DEBUG AnnotationConfiguration:375 - null<-org.dom4j.tree.DefaultAttribute@bdab91 [Attribute: name package value "com.demos.hibernatemovies"]
[hibernatetool] 14:51:15,105 INFO AnnotationConfiguration:117 - Mapping package com.demos.hibernatemovies
[hibernatetool] 14:51:15,576 WARN AnnotationBinder:157 - Package not found or wo package-info.java: com.demos.hibernatemovies
[hibernatetool] 14:51:15,576 DEBUG AnnotationConfiguration:379 - null<-org.dom4j.tree.DefaultAttribute@142a80d [Attribute: name class value "com.demos.hibernatemovies.Actor"]
[hibernatetool] 14:51:15,676 DEBUG AnnotationConfiguration:379 - null<-org.dom4j.tree.DefaultAttribute@94884d [Attribute: name class value "com.demos.hibernatemovies.ActorPK"]
[hibernatetool] 14:51:15,706 DEBUG AnnotationConfiguration:379 - null<-org.dom4j.tree.DefaultAttribute@13c468a [Attribute: name class value "com.demos.hibernatemovies.Category"]
[hibernatetool] 14:51:15,716 DEBUG AnnotationConfiguration:379 - null<-org.dom4j.tree.DefaultAttribute@ee22f7 [Attribute: name class value "com.demos.hibernatemovies.Movie"]
[hibernatetool] 14:51:15,736 DEBUG AnnotationConfiguration:379 - null<-org.dom4j.tree.DefaultAttribute@2cb49d [Attribute: name class value "com.demos.hibernatemovies.Studio"]
[hibernatetool] 14:51:15,746 INFO Configuration:1397 - Configured SessionFactory: null
[hibernatetool] 14:51:15,746 DEBUG Configuration:1398 - properties: {java.vendor=Sun Microsystems Inc., hibernate.connection.url=jdbc:rdms:host=xxx.xxx.xxx.xxx;port=xxxx;schema=ANNOTATIONS1;varchar=varChar;, sun.management.compiler=HotSpot Client Compiler, hibernate.jdbc.use_get_generated_keys=false, os.name=Windows XP, sun.boot.class.path=F:\j2se1.5.0\jre\lib\rt.jar;F:\j2se1.5.0\jre\lib\i18n.jar;F:\j2se1.5.0\jre\lib\sunrsasign.jar;F:\j2se1.5.0\jre\lib\jsse.jar;F:\j2se1.5.0\jre\lib\jce.jar;F:\j2se1.5.0\jre\lib\charsets.jar;F:\j2se1.5.0\jre\classes, hibernate.current_session_context_class=thread, sun.desktop=windows, java.vm.specification.vendor=Sun Microsystems Inc., ant.home=F:\ANT-1-6-5\apache-ant-1.6.5, java.runtime.version=1.5.0_02-b09, hibernate.cache.provider_class=org.hibernate.cache.HashtableCacheProvider, user.name=ploskikl, current_session_context_class=thread, user.language=en, sun.boot.library.path=F:\j2se1.5.0\jre\bin, hibernate.jdbc.use_scrollable_resultset=false, java.version=1.5.0_02, user.timezone=America/Chicago, sun.arch.data.model=32, hibernate.use_outer_join=true, java.endorsed.dirs=F:\j2se1.5.0\jre\lib\endorsed, sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86, sun.jnu.encoding=Cp1252, file.encoding.pkg=sun.io, file.separator=\, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=49.0, user.country=US, java.home=F:\j2se1.5.0\jre, java.vm.info=mixed mode, sharing, os.version=5.1, hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory, path.separator=;, java.vm.version=1.5.0_02-b09, hibernate.max_fetch_depth=3, hibernate.connection.password=xxxxxx, user.variant=, ant.library.dir=F:\ANT-1-6-5\apache-ant-1.6.5\lib, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, hibernate.connection.username=xxxxxx, user.home=C:\Documents and Settings\PloskiKL, hibernate.query.substitutions=true 1, false 0, java.specification.vendor=Sun Microsystems Inc., java.library.path=F:\j2se1.5.0\bin;.;C:\WINDOWS\system32;C:\WINDOWS;"F:\ANT-1-6-5\apache-ant-1.6.5"\bin;F:\j2se1.5.0\bin;F:\ANT-1-6-5\apache-ant-1.6.5\bin;F:\gnu\emacs-20.7\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\msys\1.0\bin;C:\mingw\bin;F:\PThreads-Win32-Redhat\GCE2, java.vendor.url=http://java.sun.com/, hibernate.connection.driver_class=com.unisys.os2200.rdms.jdbc.RdmsDriver, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=org.hibernate.dialect.RDMSOS2200Dialect, hibernate.jdbc.use_streams_for_binary=false, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-launcher.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-antlr.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-bcel.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-bsf.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-log4j.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-oro.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-regexp.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-resolver.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-commons-logging.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-commons-net.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-icontract.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-jai.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-javamail.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-jdepend.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-jmf.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-jsch.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-junit.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-launcher.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-netrexx.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-nodeps.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-starteam.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-stylebook.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-swing.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-trax.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-vaj.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-weblogic.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-xalan1.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-xslp.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\xercesImpl.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\xml-apis.jar;F:\j2se1.5.0\lib\tools.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, sun.cpu.endian=little, sun.os.patch.level=Service Pack 2, connection.pool_size=1, java.io.tmpdir=C:\DOCUME~1\PloskiKL\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, hibernate.default_schema=ANNOTATIONS1, hibernate.query.factory_class=org.hibernate.hql.ast.ASTQueryTranslatorFactory, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=F:\j2se1.5.0\jre\lib\ext, user.dir=F:\temp, hibernate.fetch_size=10, line.separator=
[hibernatetool] , java.vm.name=Java HotSpot(TM) Client VM, file.encoding=Cp1252, java.specification.version=1.5, hibernate.connection.isolation=2, hibernate.connection.pool_size=1, hibernate.show_sql=true}
[hibernatetool] 14:51:15,756 DEBUG AnnotationConfiguration:189 - Execute first pass mapping processing
[hibernatetool] 14:51:15,756 DEBUG AnnotationConfiguration:248 - Process hbm files
[hibernatetool] 14:51:15,756 DEBUG AnnotationConfiguration:256 - Process annotated classes
[hibernatetool] 14:51:15,786 INFO AnnotationBinder:340 - Binding entity from annotated class: java.lang.Class
[hibernatetool] 14:51:15,956 DEBUG Ejb3Column:160 - Binding column TYPE unique false
[hibernatetool] 14:51:16,047 DEBUG EntityBinder:201 - Import with entity name=Actor
[hibernatetool] 14:51:16,087 INFO EntityBinder:298 - Bind entity com.demos.hibernatemovies.Actor on table ACTOR
[hibernatetool] 14:51:16,157 DEBUG AnnotationBinder:802 - Processing com.demos.hibernatemovies.Actor per property access
[hibernatetool] 14:51:16,277 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Actor.pk
[hibernatetool] 14:51:16,327 DEBUG Ejb3Column:160 - Binding column pk unique false
[hibernatetool] 14:51:16,337 DEBUG AnnotationBinder:979 - pk is an id
[hibernatetool] 14:51:16,347 DEBUG AnnotationBinder:1435 - Binding component with path: com.demos.hibernatemovies.Actor.pk
[hibernatetool] 14:51:16,357 DEBUG AnnotationBinder:802 - Processing com.demos.hibernatemovies.ActorPK per property access
[hibernatetool] 14:51:16,357 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.ActorPK.firstName
[hibernatetool] 14:51:16,367 DEBUG Ejb3Column:337 - Column(s) overridden for property firstName
[hibernatetool] 14:51:16,367 DEBUG Ejb3Column:160 - Binding column FIRST_NAME unique false
[hibernatetool] 14:51:16,387 DEBUG PropertyBinder:93 - binding property firstName with lazy=false
[hibernatetool] 14:51:16,417 DEBUG SimpleValueBinder:183 - building SimpleValue for firstName
[hibernatetool] 14:51:16,417 DEBUG PropertyBinder:112 - Building property firstName
[hibernatetool] 14:51:16,447 DEBUG PropertyBinder:121 - Cascading firstName with null
[hibernatetool] 14:51:16,447 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.ActorPK.lastName
[hibernatetool] 14:51:16,447 DEBUG Ejb3Column:337 - Column(s) overridden for property lastName
[hibernatetool] 14:51:16,447 DEBUG Ejb3Column:160 - Binding column LAST_NAME unique false
[hibernatetool] 14:51:16,457 DEBUG PropertyBinder:93 - binding property lastName with lazy=false
[hibernatetool] 14:51:16,457 DEBUG SimpleValueBinder:183 - building SimpleValue for lastName
[hibernatetool] 14:51:16,457 DEBUG PropertyBinder:112 - Building property lastName
[hibernatetool] 14:51:16,457 DEBUG PropertyBinder:121 - Cascading lastName with null
[hibernatetool] 14:51:16,467 DEBUG PropertyBinder:112 - Building property pk
[hibernatetool] 14:51:16,467 DEBUG PropertyBinder:121 - Cascading pk with null
[hibernatetool] 14:51:16,477 DEBUG AnnotationBinder:1019 - Bind @Id on pk
[hibernatetool] 14:51:16,477 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Actor.movies
[hibernatetool] 14:51:16,487 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:16,637 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:16,637 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:16,647 DEBUG CollectionBinder:247 - Collection role: com.demos.hibernatemovies.Actor.movies
[hibernatetool] 14:51:16,668 DEBUG PropertyBinder:112 - Building property movies
[hibernatetool] 14:51:16,668 DEBUG PropertyBinder:121 - Cascading movies with persist,merge
[hibernatetool] 14:51:16,778 DEBUG ClassValidator:93 - ResourceBundle ValidatorMessages not found. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
[hibernatetool] 14:51:16,828 INFO AnnotationBinder:340 - Binding entity from annotated class: java.lang.Class
[hibernatetool] 14:51:16,838 DEBUG Ejb3Column:160 - Binding column TYPE unique false
[hibernatetool] 14:51:16,838 DEBUG EntityBinder:201 - Import with entity name=Category
[hibernatetool] 14:51:16,838 INFO EntityBinder:298 - Bind entity com.demos.hibernatemovies.Category on table CATEGORY
[hibernatetool] 14:51:16,838 DEBUG AnnotationBinder:802 - Processing com.demos.hibernatemovies.Category per property access
[hibernatetool] 14:51:16,858 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Category.category
[hibernatetool] 14:51:16,858 DEBUG Ejb3Column:160 - Binding column CATEGORY unique false
[hibernatetool] 14:51:16,858 DEBUG AnnotationBinder:979 - category is an id
[hibernatetool] 14:51:16,858 DEBUG SimpleValueBinder:183 - building SimpleValue for category
[hibernatetool] 14:51:16,868 DEBUG PropertyBinder:112 - Building property category
[hibernatetool] 14:51:16,868 DEBUG PropertyBinder:121 - Cascading category with null
[hibernatetool] 14:51:16,868 DEBUG AnnotationBinder:1019 - Bind @EmbeddedId on category
[hibernatetool] 14:51:16,868 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Category.movies
[hibernatetool] 14:51:16,868 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:16,878 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:16,878 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:16,878 DEBUG CollectionBinder:247 - Collection role: com.demos.hibernatemovies.Category.movies
[hibernatetool] 14:51:16,878 DEBUG PropertyBinder:112 - Building property movies
[hibernatetool] 14:51:16,878 DEBUG PropertyBinder:121 - Cascading movies with persist,merge
[hibernatetool] 14:51:16,878 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Category.description
[hibernatetool] 14:51:16,878 DEBUG Ejb3Column:160 - Binding column DESC unique false
[hibernatetool] 14:51:16,888 DEBUG PropertyBinder:93 - binding property description with lazy=false
[hibernatetool] 14:51:16,888 DEBUG SimpleValueBinder:183 - building SimpleValue for description
[hibernatetool] 14:51:16,888 DEBUG PropertyBinder:112 - Building property description
[hibernatetool] 14:51:16,888 DEBUG PropertyBinder:121 - Cascading description with null
[hibernatetool] 14:51:16,898 DEBUG ClassValidator:93 - ResourceBundle ValidatorMessages not found. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
[hibernatetool] 14:51:16,938 INFO AnnotationBinder:340 - Binding entity from annotated class: java.lang.Class
[hibernatetool] 14:51:16,938 DEBUG Ejb3Column:160 - Binding column TYPE unique false
[hibernatetool] 14:51:16,938 DEBUG EntityBinder:201 - Import with entity name=Movie
[hibernatetool] 14:51:16,938 INFO EntityBinder:298 - Bind entity com.demos.hibernatemovies.Movie on table MOVIE
[hibernatetool] 14:51:16,938 DEBUG AnnotationBinder:802 - Processing com.demos.hibernatemovies.Movie per property access
[hibernatetool] 14:51:16,978 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Movie.movieTitle
[hibernatetool] 14:51:16,978 DEBUG Ejb3Column:160 - Binding column MOVIE_TITLE unique false
[hibernatetool] 14:51:16,988 DEBUG AnnotationBinder:979 - movieTitle is an id
[hibernatetool] 14:51:16,988 DEBUG SimpleValueBinder:183 - building SimpleValue for movieTitle
[hibernatetool] 14:51:16,988 DEBUG PropertyBinder:112 - Building property movieTitle
[hibernatetool] 14:51:16,988 DEBUG PropertyBinder:121 - Cascading movieTitle with null
[hibernatetool] 14:51:16,988 DEBUG AnnotationBinder:1019 - Bind @EmbeddedId on movieTitle
[hibernatetool] 14:51:16,998 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Movie.releaseDate
[hibernatetool] 14:51:16,998 DEBUG Ejb3Column:160 - Binding column REL_DATE unique false
[hibernatetool] 14:51:16,998 DEBUG PropertyBinder:93 - binding property releaseDate with lazy=false
[hibernatetool] 14:51:16,998 DEBUG SimpleValueBinder:183 - building SimpleValue for releaseDate
[hibernatetool] 14:51:16,998 DEBUG PropertyBinder:112 - Building property releaseDate
[hibernatetool] 14:51:17,008 DEBUG PropertyBinder:121 - Cascading releaseDate with null
[hibernatetool] 14:51:17,008 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Movie.studio
[hibernatetool] 14:51:17,008 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:17,008 DEBUG Ejb3Column:160 - Binding column studio unique false
[hibernatetool] 14:51:17,018 DEBUG PropertyBinder:112 - Building property studio
[hibernatetool] 14:51:17,018 DEBUG PropertyBinder:121 - Cascading studio with none
[hibernatetool] 14:51:17,018 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Movie.budget
[hibernatetool] 14:51:17,028 DEBUG Ejb3Column:160 - Binding column BUDGET unique false
[hibernatetool] 14:51:17,028 DEBUG PropertyBinder:93 - binding property budget with lazy=false
[hibernatetool] 14:51:17,028 DEBUG SimpleValueBinder:183 - building SimpleValue for budget
[hibernatetool] 14:51:17,028 DEBUG PropertyBinder:112 - Building property budget
[hibernatetool] 14:51:17,028 DEBUG PropertyBinder:121 - Cascading budget with null
[hibernatetool] 14:51:17,038 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Movie.actors
[hibernatetool] 14:51:17,038 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:17,038 DEBUG Ejb3Column:160 - Binding column MOVIE_TITLE unique false
[hibernatetool] 14:51:17,038 DEBUG Ejb3Column:160 - Binding column FIRST_NAME unique false
[hibernatetool] 14:51:17,048 DEBUG Ejb3Column:160 - Binding column LAST_NAME unique false
[hibernatetool] 14:51:17,048 DEBUG CollectionBinder:247 - Collection role: com.demos.hibernatemovies.Movie.actors
[hibernatetool] 14:51:17,048 DEBUG PropertyBinder:112 - Building property actors
[hibernatetool] 14:51:17,048 DEBUG PropertyBinder:121 - Cascading actors with persist,merge
[hibernatetool] 14:51:17,048 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Movie.categories
[hibernatetool] 14:51:17,058 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:17,058 DEBUG Ejb3Column:160 - Binding column MOVIE_TITLE unique false
[hibernatetool] 14:51:17,058 DEBUG Ejb3Column:160 - Binding column CATEGORY unique false
[hibernatetool] 14:51:17,058 DEBUG CollectionBinder:247 - Collection role: com.demos.hibernatemovies.Movie.categories
[hibernatetool] 14:51:17,058 DEBUG PropertyBinder:112 - Building property categories
[hibernatetool] 14:51:17,058 DEBUG PropertyBinder:121 - Cascading categories with persist,merge
[hibernatetool] 14:51:17,068 DEBUG ClassValidator:93 - ResourceBundle ValidatorMessages not found. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
[hibernatetool] 14:51:17,108 INFO AnnotationBinder:340 - Binding entity from annotated class: java.lang.Class
[hibernatetool] 14:51:17,108 DEBUG Ejb3Column:160 - Binding column TYPE unique false
[hibernatetool] 14:51:17,108 DEBUG EntityBinder:201 - Import with entity name=Studio
[hibernatetool] 14:51:17,108 INFO EntityBinder:298 - Bind entity com.demos.hibernatemovies.Studio on table STUDIO
[hibernatetool] 14:51:17,108 DEBUG AnnotationBinder:802 - Processing com.demos.hibernatemovies.Studio per property access
[hibernatetool] 14:51:17,128 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Studio.studioName
[hibernatetool] 14:51:17,128 DEBUG Ejb3Column:160 - Binding column STUDIO_NAME unique false
[hibernatetool] 14:51:17,128 DEBUG AnnotationBinder:979 - studioName is an id
[hibernatetool] 14:51:17,128 DEBUG SimpleValueBinder:183 - building SimpleValue for studioName
[hibernatetool] 14:51:17,128 DEBUG PropertyBinder:112 - Building property studioName
[hibernatetool] 14:51:17,138 DEBUG PropertyBinder:121 - Cascading studioName with null
[hibernatetool] 14:51:17,138 DEBUG AnnotationBinder:1019 - Bind @EmbeddedId on studioName
[hibernatetool] 14:51:17,138 DEBUG AnnotationBinder:865 - Processing annotations of com.demos.hibernatemovies.Studio.movies
[hibernatetool] 14:51:17,138 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:17,138 DEBUG Ejb3Column:160 - Binding column movies unique false
[hibernatetool] 14:51:17,138 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:17,148 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:17,148 DEBUG Ejb3Column:160 - Binding column null unique false
[hibernatetool] 14:51:17,148 DEBUG CollectionBinder:247 - Collection role: com.demos.hibernatemovies.Studio.movies
[hibernatetool] 14:51:17,148 DEBUG PropertyBinder:112 - Building property movies
[hibernatetool] 14:51:17,148 DEBUG PropertyBinder:121 - Cascading movies with all
[hibernatetool] 14:51:17,158 DEBUG ClassValidator:93 - ResourceBundle ValidatorMessages not found. Delegate to org.hibernate.validator.resources.DefaultValidatorMessages
[hibernatetool] 14:51:17,188 DEBUG AnnotationConfiguration:216 - processing manytoone fk mappings
[hibernatetool] 14:51:17,639 INFO Configuration:1022 - processing extends queue
[hibernatetool] 14:51:17,639 INFO Configuration:1026 - processing collection mappings
[hibernatetool] 14:51:17,649 DEBUG CollectionSecondPass:33 - Second pass for collection: com.demos.hibernatemovies.Movie.categories
[hibernatetool] 14:51:17,649 DEBUG CollectionBinder:685 - Binding as ManyToMany: com.demos.hibernatemovies.Movie.categories
[hibernatetool] 14:51:17,659 DEBUG CollectionSecondPass:49 - Mapped collection key: MOVIE_TITLE, element: CATEGORY
[hibernatetool] 14:51:17,659 DEBUG CollectionSecondPass:33 - Second pass for collection: com.demos.hibernatemovies.Movie.actors
[hibernatetool] 14:51:17,659 DEBUG CollectionBinder:685 - Binding as ManyToMany: com.demos.hibernatemovies.Movie.actors
[hibernatetool] 14:51:17,669 DEBUG CollectionSecondPass:49 - Mapped collection key: MOVIE_TITLE, element: FIRST_NAME, LAST_NAME
[hibernatetool] 14:51:17,669 DEBUG CollectionSecondPass:33 - Second pass for collection: com.demos.hibernatemovies.Actor.movies
[hibernatetool] 14:51:17,669 DEBUG CollectionBinder:685 - Binding as ManyToMany: com.demos.hibernatemovies.Actor.movies
[hibernatetool] 14:51:17,669 DEBUG TableBinder:154 - Retrieving property com.demos.hibernatemovies.Movie.actors
[hibernatetool] 14:51:17,679 DEBUG CollectionSecondPass:49 - Mapped collection key: FIRST_NAME, LAST_NAME, element: MOVIE_TITLE
[hibernatetool] 14:51:17,679 DEBUG CollectionSecondPass:33 - Second pass for collection: com.demos.hibernatemovies.Category.movies
[hibernatetool] 14:51:17,679 DEBUG CollectionBinder:685 - Binding as ManyToMany: com.demos.hibernatemovies.Category.movies
[hibernatetool] 14:51:17,679 DEBUG TableBinder:154 - Retrieving property com.demos.hibernatemovies.Movie.categories
[hibernatetool] 14:51:17,679 DEBUG CollectionSecondPass:49 - Mapped collection key: CATEGORY, element: MOVIE_TITLE
[hibernatetool] 14:51:17,679 DEBUG CollectionSecondPass:33 - Second pass for collection: com.demos.hibernatemovies.Studio.movies
[hibernatetool] 14:51:17,679 DEBUG CollectionBinder:441 - Binding a OneToMany: com.demos.hibernatemovies.Studio.movies through a foreign key
[hibernatetool] 14:51:17,689 INFO CollectionBinder:471 - Mapping collection: com.demos.hibernatemovies.Studio.movies -> MOVIE
[hibernatetool] 14:51:17,689 DEBUG TableBinder:154 - Retrieving property com.demos.hibernatemovies.Movie.studio
[hibernatetool] 14:51:17,689 DEBUG CollectionSecondPass:49 - Mapped collection key: studio_STUDIO_NAME, one-to-many: com.demos.hibernatemovies.Movie
[hibernatetool] 14:51:17,689 INFO Configuration:1035 - processing association property references
[hibernatetool] 14:51:17,699 INFO Configuration:1057 - processing foreign key constraints
[hibernatetool] 14:51:17,699 DEBUG Configuration:1108 - resolving reference to class: com.demos.hibernatemovies.Studio
[hibernatetool] 14:51:17,699 DEBUG Configuration:1108 - resolving reference to class: com.demos.hibernatemovies.Movie
[hibernatetool] 14:51:17,699 DEBUG Configuration:1108 - resolving reference to class: com.demos.hibernatemovies.Actor
[hibernatetool] 14:51:17,699 DEBUG Configuration:1108 - resolving reference to class: com.demos.hibernatemovies.Movie
[hibernatetool] 14:51:17,699 DEBUG Configuration:1108 - resolving reference to class: com.demos.hibernatemovies.Category
[hibernatetool] 14:51:17,729 DEBUG HibernateMappingExporter:129 - org.hibernate.tool.hbm2x.HibernateMappingExporter outputdir:F:\temp\Annotations-HBM-XML templatePrefix: null path: []
[hibernatetool] 14:51:17,929 DEBUG template:91 - CommonsLogLogSystem name is 'org.hibernate.tool.hbm2x.template'
[hibernatetool] 14:51:17,939 INFO template:88 - **************************************************************
[hibernatetool] 14:51:17,939 INFO template:88 - Starting Jakarta Velocity v1.4
[hibernatetool] 14:51:17,939 INFO template:88 - RuntimeInstance initializing.
[hibernatetool] 14:51:17,939 INFO template:88 - Default Properties File: org\apache\velocity\runtime\defaults\velocity.properties
[hibernatetool] 14:51:17,939 INFO template:88 - Trying to use logger class org.apache.velocity.tools.generic.log.CommonsLogLogSystem
[hibernatetool] 14:51:17,949 INFO template:88 - Using logger class org.apache.velocity.tools.generic.log.CommonsLogLogSystem
[hibernatetool] 14:51:17,959 INFO template:88 - Default ResourceManager initializing. (class org.apache.velocity.runtime.resource.ResourceManagerImpl)
[hibernatetool] 14:51:17,969 INFO template:88 - Resource Loader Instantiated: org.apache.velocity.runtime.resource.loader.FileResourceLoader
[hibernatetool] 14:51:17,979 INFO template:88 - FileResourceLoader : initialization starting.
[hibernatetool] 14:51:17,979 INFO template:88 - FileResourceLoader : adding path '.'
[hibernatetool] 14:51:17,979 INFO template:88 - FileResourceLoader : initialization complete.
[hibernatetool] 14:51:17,979 INFO template:88 - Resource Loader Instantiated: org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:17,979 INFO template:88 - ClasspathResourceLoader : initialization starting.
[hibernatetool] 14:51:17,989 INFO template:88 - ClasspathResourceLoader : initialization complete.
[hibernatetool] 14:51:17,999 INFO template:88 - ResourceCache : initialized. (class org.apache.velocity.runtime.resource.ResourceCacheImpl)
[hibernatetool] 14:51:17,999 INFO template:88 - Default ResourceManager initialization complete.
[hibernatetool] 14:51:18,009 INFO template:88 - Loaded System Directive: org.apache.velocity.runtime.directive.Literal
[hibernatetool] 14:51:18,019 INFO template:88 - Loaded System Directive: org.apache.velocity.runtime.directive.Macro
[hibernatetool] 14:51:18,029 INFO template:88 - Loaded System Directive: org.apache.velocity.runtime.directive.Parse
[hibernatetool] 14:51:18,039 INFO template:88 - Loaded System Directive: org.apache.velocity.runtime.directive.Include
[hibernatetool] 14:51:18,039 INFO template:88 - Loaded System Directive: org.apache.velocity.runtime.directive.Foreach
[hibernatetool] 14:51:18,470 INFO template:88 - Created: 20 parsers.
[hibernatetool] 14:51:18,480 INFO template:88 - Velocimacro : initialization starting.
[hibernatetool] 14:51:18,480 INFO template:88 - Velocimacro : allowInline = true : VMs can be defined inline in templates
[hibernatetool] 14:51:18,480 INFO template:88 - Velocimacro : allowInlineToOverride = false : VMs defined inline may NOT replace previous VM definitions
[hibernatetool] 14:51:18,480 INFO template:88 - Velocimacro : allowInlineLocal = false : VMs defined inline will be global in scope if allowed.
[hibernatetool] 14:51:18,480 INFO template:88 - Velocimacro : messages on : VM system will output logging messages
[hibernatetool] 14:51:18,490 INFO template:88 - Velocimacro : autoload off : VM system will not automatically reload global library macros
[hibernatetool] 14:51:18,490 INFO template:88 - Velocimacro : initialization complete.
[hibernatetool] 14:51:18,490 INFO template:88 - Velocity successfully started.
[hibernatetool] 14:51:18,510 DEBUG TemplateHelper:220 - putInContext exporter=org.hibernate.tool.hbm2x.HibernateMappingExporter@1bb5c09
[hibernatetool] 14:51:18,510 DEBUG TemplateHelper:220 - putInContext c2h=org.hibernate.tool.hbm2x.Cfg2HbmTool@1976011
[hibernatetool] 14:51:18,510 DEBUG TemplateHelper:220 - putInContext c2j=org.hibernate.tool.hbm2x.Cfg2JavaTool@1242b11
[hibernatetool] 14:51:18,520 DEBUG TemplateHelper:220 - putInContext outputdir=F:\temp\Annotations-HBM-XML
[hibernatetool] 14:51:18,520 DEBUG TemplateHelper:220 - putInContext template_path=[Ljava.lang.String;@1878144
[hibernatetool] 14:51:18,520 DEBUG TemplateHelper:220 - putInContext java.vendor=Sun Microsystems Inc.
[hibernatetool] 14:51:18,520 DEBUG TemplateHelper:220 - putInContext hibernate.connection.url=jdbc:rdms:host=xxx.xxx.xxx.xxx;port=xxxx;schema=ANNOTATIONS1;varchar=varChar;
[hibernatetool] 14:51:18,520 DEBUG TemplateHelper:220 - putInContext sun.management.compiler=HotSpot Client Compiler
[hibernatetool] 14:51:18,520 DEBUG TemplateHelper:220 - putInContext hibernate.jdbc.use_get_generated_keys=false
[hibernatetool] 14:51:18,520 DEBUG TemplateHelper:220 - putInContext os.name=Windows XP
[hibernatetool] 14:51:18,530 DEBUG TemplateHelper:220 - putInContext sun.boot.class.path=F:\j2se1.5.0\jre\lib\rt.jar;F:\j2se1.5.0\jre\lib\i18n.jar;F:\j2se1.5.0\jre\lib\sunrsasign.jar;F:\j2se1.5.0\jre\lib\jsse.jar;F:\j2se1.5.0\jre\lib\jce.jar;F:\j2se1.5.0\jre\lib\charsets.jar;F:\j2se1.5.0\jre\classes
[hibernatetool] 14:51:18,530 DEBUG TemplateHelper:220 - putInContext hibernate.current_session_context_class=thread
[hibernatetool] 14:51:18,530 DEBUG TemplateHelper:220 - putInContext sun.desktop=windows
[hibernatetool] 14:51:18,530 DEBUG TemplateHelper:220 - putInContext java.vm.specification.vendor=Sun Microsystems Inc.
[hibernatetool] 14:51:18,530 DEBUG TemplateHelper:220 - putInContext ant.home=F:\ANT-1-6-5\apache-ant-1.6.5
[hibernatetool] 14:51:18,530 DEBUG TemplateHelper:220 - putInContext java.runtime.version=1.5.0_02-b09
[hibernatetool] 14:51:18,530 DEBUG TemplateHelper:220 - putInContext hibernate.cache.provider_class=org.hibernate.cache.HashtableCacheProvider
[hibernatetool] 14:51:18,540 DEBUG TemplateHelper:220 - putInContext user.name=ploskikl
[hibernatetool] 14:51:18,540 DEBUG TemplateHelper:220 - putInContext current_session_context_class=thread
[hibernatetool] 14:51:18,540 DEBUG TemplateHelper:220 - putInContext user.language=en
[hibernatetool] 14:51:18,540 DEBUG TemplateHelper:220 - putInContext sun.boot.library.path=F:\j2se1.5.0\jre\bin
[hibernatetool] 14:51:18,550 DEBUG TemplateHelper:220 - putInContext hibernate.jdbc.use_scrollable_resultset=false
[hibernatetool] 14:51:18,550 DEBUG TemplateHelper:220 - putInContext java.version=1.5.0_02
[hibernatetool] 14:51:18,550 DEBUG TemplateHelper:220 - putInContext user.timezone=America/Chicago
[hibernatetool] 14:51:18,550 DEBUG TemplateHelper:220 - putInContext sun.arch.data.model=32
[hibernatetool] 14:51:18,550 DEBUG TemplateHelper:220 - putInContext hibernate.use_outer_join=true
[hibernatetool] 14:51:18,550 DEBUG TemplateHelper:220 - putInContext java.endorsed.dirs=F:\j2se1.5.0\jre\lib\endorsed
[hibernatetool] 14:51:18,550 DEBUG TemplateHelper:220 - putInContext sun.cpu.isalist=pentium_pro+mmx pentium_pro pentium+mmx pentium i486 i386 i86
[hibernatetool] 14:51:18,560 DEBUG TemplateHelper:220 - putInContext sun.jnu.encoding=Cp1252
[hibernatetool] 14:51:18,560 DEBUG TemplateHelper:220 - putInContext file.encoding.pkg=sun.io
[hibernatetool] 14:51:18,560 DEBUG TemplateHelper:220 - putInContext file.separator=\
[hibernatetool] 14:51:18,560 DEBUG TemplateHelper:220 - putInContext java.specification.name=Java Platform API Specification
[hibernatetool] 14:51:18,560 DEBUG TemplateHelper:220 - putInContext hibernate.cglib.use_reflection_optimizer=true
[hibernatetool] 14:51:18,560 DEBUG TemplateHelper:220 - putInContext java.class.version=49.0
[hibernatetool] 14:51:18,560 DEBUG TemplateHelper:220 - putInContext user.country=US
[hibernatetool] 14:51:18,560 DEBUG TemplateHelper:220 - putInContext java.home=F:\j2se1.5.0\jre
[hibernatetool] 14:51:18,570 DEBUG TemplateHelper:220 - putInContext java.vm.info=mixed mode, sharing
[hibernatetool] 14:51:18,570 DEBUG TemplateHelper:220 - putInContext os.version=5.1
[hibernatetool] 14:51:18,570 DEBUG TemplateHelper:220 - putInContext hibernate.transaction.factory_class=org.hibernate.transaction.JDBCTransactionFactory
[hibernatetool] 14:51:18,570 DEBUG TemplateHelper:220 - putInContext path.separator=;
[hibernatetool] 14:51:18,570 DEBUG TemplateHelper:220 - putInContext java.vm.version=1.5.0_02-b09
[hibernatetool] 14:51:18,570 DEBUG TemplateHelper:220 - putInContext hibernate.max_fetch_depth=3
[hibernatetool] 14:51:18,570 DEBUG TemplateHelper:220 - putInContext hibernate.connection.password=xxxxxx
[hibernatetool] 14:51:18,580 DEBUG TemplateHelper:220 - putInContext user.variant=
[hibernatetool] 14:51:18,580 DEBUG TemplateHelper:220 - putInContext ant.library.dir=F:\ANT-1-6-5\apache-ant-1.6.5\lib
[hibernatetool] 14:51:18,580 DEBUG TemplateHelper:220 - putInContext java.awt.printerjob=sun.awt.windows.WPrinterJob
[hibernatetool] 14:51:18,580 DEBUG TemplateHelper:220 - putInContext sun.io.unicode.encoding=UnicodeLittle
[hibernatetool] 14:51:18,580 DEBUG TemplateHelper:220 - putInContext awt.toolkit=sun.awt.windows.WToolkit
[hibernatetool] 14:51:18,580 DEBUG TemplateHelper:220 - putInContext hibernate.connection.username=xxxxxx
[hibernatetool] 14:51:18,590 DEBUG TemplateHelper:220 - putInContext user.home=C:\Documents and Settings\PloskiKL
[hibernatetool] 14:51:18,590 DEBUG TemplateHelper:220 - putInContext hibernate.query.substitutions=true 1, false 0
[hibernatetool] 14:51:18,590 DEBUG TemplateHelper:220 - putInContext java.specification.vendor=Sun Microsystems Inc.
[hibernatetool] 14:51:18,590 DEBUG TemplateHelper:220 - putInContext java.library.path=F:\j2se1.5.0\bin;.;C:\WINDOWS\system32;C:\WINDOWS;"F:\ANT-1-6-5\apache-ant-1.6.5"\bin;F:\j2se1.5.0\bin;F:\ANT-1-6-5\apache-ant-1.6.5\bin;F:\gnu\emacs-20.7\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\msys\1.0\bin;C:\mingw\bin;F:\PThreads-Win32-Redhat\GCE2
[hibernatetool] 14:51:18,590 DEBUG TemplateHelper:220 - putInContext java.vendor.url=http://java.sun.com/
[hibernatetool] 14:51:18,590 DEBUG TemplateHelper:220 - putInContext hibernate.connection.driver_class=com.unisys.os2200.rdms.jdbc.RdmsDriver
[hibernatetool] 14:51:18,590 DEBUG TemplateHelper:220 - putInContext java.vm.vendor=Sun Microsystems Inc.
[hibernatetool] 14:51:18,600 DEBUG TemplateHelper:220 - putInContext hibernate.dialect=org.hibernate.dialect.RDMSOS2200Dialect
[hibernatetool] 14:51:18,600 DEBUG TemplateHelper:220 - putInContext hibernate.jdbc.use_streams_for_binary=false
[hibernatetool] 14:51:18,600 DEBUG TemplateHelper:220 - putInContext java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition
[hibernatetool] 14:51:18,600 DEBUG TemplateHelper:220 - putInContext java.class.path=F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-launcher.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-antlr.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-bcel.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-bsf.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-log4j.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-oro.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-regexp.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-apache-resolver.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-commons-logging.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-commons-net.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-icontract.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-jai.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-javamail.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-jdepend.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-jmf.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-jsch.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-junit.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-launcher.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-netrexx.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-nodeps.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-starteam.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-stylebook.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-swing.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-trax.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-vaj.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-weblogic.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-xalan1.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant-xslp.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\ant.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\xercesImpl.jar;F:\ANT-1-6-5\apache-ant-1.6.5\lib\xml-apis.jar;F:\j2se1.5.0\lib\tools.jar
[hibernatetool] 14:51:18,600 DEBUG TemplateHelper:220 - putInContext java.vm.specification.name=Java Virtual Machine Specification
[hibernatetool] 14:51:18,600 DEBUG TemplateHelper:220 - putInContext java.vm.specification.version=1.0
[hibernatetool] 14:51:18,610 DEBUG TemplateHelper:220 - putInContext sun.cpu.endian=little
[hibernatetool] 14:51:18,610 DEBUG TemplateHelper:220 - putInContext sun.os.patch.level=Service Pack 2
[hibernatetool] 14:51:18,610 DEBUG TemplateHelper:220 - putInContext connection.pool_size=1
[hibernatetool] 14:51:18,610 DEBUG TemplateHelper:220 - putInContext java.io.tmpdir=C:\DOCUME~1\PloskiKL\LOCALS~1\Temp\
[hibernatetool] 14:51:18,610 DEBUG TemplateHelper:220 - putInContext java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi
[hibernatetool] 14:51:18,610 DEBUG TemplateHelper:220 - putInContext hibernate.default_schema=ANNOTATIONS1
[hibernatetool] 14:51:18,610 DEBUG TemplateHelper:220 - putInContext hibernate.query.factory_class=org.hibernate.hql.ast.ASTQueryTranslatorFactory
[hibernatetool] 14:51:18,610 DEBUG TemplateHelper:220 - putInContext os.arch=x86
[hibernatetool] 14:51:18,620 DEBUG TemplateHelper:220 - putInContext java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment
[hibernatetool] 14:51:18,620 DEBUG TemplateHelper:220 - putInContext java.ext.dirs=F:\j2se1.5.0\jre\lib\ext
[hibernatetool] 14:51:18,620 DEBUG TemplateHelper:220 - putInContext user.dir=F:\temp
[hibernatetool] 14:51:18,620 DEBUG TemplateHelper:220 - putInContext hibernate.fetch_size=10
[hibernatetool] 14:51:18,630 DEBUG TemplateHelper:220 - putInContext line.separator=

[hibernatetool] 14:51:18,630 DEBUG TemplateHelper:220 - putInContext java.vm.name=Java HotSpot(TM) Client VM
[hibernatetool] 14:51:18,630 DEBUG TemplateHelper:220 - putInContext file.encoding=Cp1252
[hibernatetool] 14:51:18,630 DEBUG TemplateHelper:220 - putInContext java.specification.version=1.5
[hibernatetool] 14:51:18,630 DEBUG TemplateHelper:220 - putInContext hibernate.connection.isolation=2
[hibernatetool] 14:51:18,630 DEBUG TemplateHelper:220 - putInContext hibernate.connection.pool_size=1
[hibernatetool] 14:51:18,630 DEBUG TemplateHelper:220 - putInContext hibernate.show_sql=true
[hibernatetool] 14:51:18,640 DEBUG TemplateHelper:220 - putInContext artifacts=org.hibernate.tool.hbm2x.ArtifactCollector@137d090
[hibernatetool] 14:51:18,640 DEBUG TemplateHelper:220 - putInContext cfg=org.hibernate.cfg.AnnotationConfiguration@15db314
[hibernatetool] 14:51:18,640 DEBUG TemplateHelper:220 - putInContext hmgs=org.hibernate.tool.hbm2x.HibernateMappingGlobalSettings@97eded
[hibernatetool] 14:51:18,700 DEBUG TemplateHelper:220 - putInContext clazz=org.hibernate.mapping.RootClass(com.demos.hibernatemovies.Category)
[hibernatetool] 14:51:18,801 INFO template:88 - ResourceManager : found hbm/hibernate-mapping.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:18,881 INFO template:88 - ResourceManager : found hbm/persistentclass.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:18,971 DEBUG TemplateHelper:220 - putInContext property=org.hibernate.mapping.Property(category)
[hibernatetool] 14:51:19,041 INFO template:88 - ResourceManager : found hbm/id.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:19,291 INFO template:88 - ResourceManager : found hbm/pkcolumn.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:19,301 DEBUG TemplateHelper:227 - removeFromContext property=org.hibernate.mapping.Property(category)
[hibernatetool] 14:51:19,301 DEBUG TemplateHelper:220 - putInContext property=org.hibernate.mapping.Property(movies)
[hibernatetool] 14:51:19,321 INFO template:88 - ResourceManager : found hbm/set.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:19,331 INFO template:88 - ResourceManager : found hbm/column.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:19,331 DEBUG TemplateHelper:227 - removeFromContext property=org.hibernate.mapping.Property(movies)
[hibernatetool] 14:51:19,331 DEBUG TemplateHelper:220 - putInContext property=org.hibernate.mapping.Property(description)
[hibernatetool] 14:51:19,361 INFO template:88 - ResourceManager : found hbm/property.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:19,381 INFO template:88 - ResourceManager : found hbm/column.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:19,381 DEBUG TemplateHelper:227 - removeFromContext property=org.hibernate.mapping.Property(description)
[hibernatetool] 14:51:19,381 DEBUG TemplateHelper:227 - removeFromContext clazz=org.hibernate.mapping.RootClass(com.demos.hibernatemovies.Category)
[hibernatetool] 14:51:19,822 DEBUG XMLPrettyPrinter:87 - XMLPrettyPrinting F:\temp\Annotations-HBM-XML\com\demos\hibernatemovies\Category.hbm.xml
[hibernatetool] 14:51:20,012 DEBUG TemplateHelper:220 - putInContext clazz=org.hibernate.mapping.RootClass(com.demos.hibernatemovies.Studio)
[hibernatetool] 14:51:20,022 INFO template:88 - ResourceManager : found hbm/hibernate-mapping.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:20,072 INFO template:88 - ResourceManager : found hbm/persistentclass.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:20,082 DEBUG TemplateHelper:220 - putInContext property=org.hibernate.mapping.Property(studioName)
[hibernatetool] 14:51:20,133 INFO template:88 - ResourceManager : found hbm/id.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:20,133 INFO template:88 - ResourceManager : found hbm/pkcolumn.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:20,133 DEBUG TemplateHelper:227 - removeFromContext property=org.hibernate.mapping.Property(studioName)
[hibernatetool] 14:51:20,143 DEBUG TemplateHelper:220 - putInContext property=org.hibernate.mapping.Property(movies)
[hibernatetool] 14:51:20,153 INFO template:88 - ResourceManager : found hbm/set.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:20,153 INFO template:88 - ResourceManager : found hbm/column.hbm.vm with loader org.apache.velocity.runtime.resource.loader.ClasspathResourceLoader
[hibernatetool] 14:51:20,153 DEBUG TemplateHelper:227 - removeFromContext property=org.hibernate.mapping.Property(movies)
[hibernatetool] 14:51:20,153 DEBUG TemplateHelper:227 - removeFromContext clazz=org.hibernate.mapping.RootClass(com.demos.hibernatemovies.Studio)
[hibernatetool] 14:51:20,173 DEBUG XMLPrettyPrinter:87 - XMLPrettyPrinting F:\temp\Annotations-HBM-XML\com\demos\hibernatemovies\Studio.hbm.xml
[hibernatetool] 14:51:20,203 DEBUG TemplateHelper: