hmm the last message didnt get all the details, im trying again
C:\java\j2sdk1.4.2_05\bin\java -Didea.launcher.port=7535 -Didea.launcher.library=C:\java\IntelliJ-IDEA-4.5\bin\breakgen.dll -Dfile.encoding=windows-1252 -classpath "C:\java\j2sdk1.4.2_05\jre\lib\charsets.jar;C:\java\j2sdk1.4.2_05\jre\lib\jce.jar;C:\java\j2sdk1.4.2_05\jre\lib\jsse.jar;C:\java\j2sdk1.4.2_05\jre\lib\plugin.jar;C:\java\j2sdk1.4.2_05\jre\lib\rt.jar;C:\java\j2sdk1.4.2_05\jre\lib\sunrsasign.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\dnsns.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\ldapsec.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\localedata.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\sunjce_provider.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\build\standalone;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\dom4j-1.4.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\hsqldb.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\commons-collections-2.1.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\commons-logging-1.0.4.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\ejb-api.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\odmg-3.0.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\log4j-1.2.8.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\c3p0-0.8.4.5.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\ant-junit-1.6.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\jsdk23.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\commons-lang-1.0.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\junit-3.8.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\hibernate-2.1.5.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\jta-api.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\cglib-full-2.0.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\ehcache-0.9.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\mysql-connector-java-3.0.11-stable-bin.jar;C:\java\IntelliJ-IDEA-4.5\lib\idea_rt.jar" com.intellij.rt.execution.application.AppMain com.intellij.rt.execution.junit2.JUnitStarter -ideVersion5 org.hibernate.auction.test.AllTests
15:31:54,421 INFO Environment: Hibernate 2.1.5
15:31:54,421 INFO Environment: hibernate.properties not found
15:31:54,453 INFO Environment: using CGLIB reflection optimizer
15:31:54,625 INFO Configuration: configuring from resource: /hibernate.cfg.xml
15:31:54,625 INFO Configuration: Configuration resource: /hibernate.cfg.xml
15:31:54,687 DEBUG DTDEntityResolver: trying to locate
http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath under net/sf/hibernate/
15:31:54,687 DEBUG DTDEntityResolver: found
http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath
15:31:54,781 DEBUG Configuration: dialect=net.sf.hibernate.dialect.MySQLDialect
15:31:54,781 DEBUG Configuration: connection.driver_class=org.gjt.mm.mysql.Driver
15:31:54,796 DEBUG Configuration: connection.url=jdbc:mysql://127.0.0.1/hia
15:31:54,796 DEBUG Configuration: connection.username=root
15:31:54,796 DEBUG Configuration: connection.password=losixx
15:31:54,796 DEBUG Configuration: connection.pool_size=1
15:31:54,796 DEBUG Configuration: cache.provider_class=net.sf.ehcache.hibernate.Provider
15:31:54,796 DEBUG Configuration: cache.use_query_cache=false
15:31:54,796 DEBUG Configuration: cache.use_minimal_puts=false
15:31:54,796 DEBUG Configuration: show_sql=false
15:31:54,796 DEBUG Configuration: null<-org.dom4j.tree.DefaultAttribute@fec107 [Attribute: name resource value "org/hibernate/auction/model/User.hbm.xml"]
15:31:54,796 INFO Configuration: Mapping resource: org/hibernate/auction/model/User.hbm.xml
15:31:54,812 DEBUG DTDEntityResolver: trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
15:31:54,812 DEBUG DTDEntityResolver: found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
15:31:54,921 INFO Binder: Mapping class: org.hibernate.auction.model.User -> USERS
15:31:54,968 DEBUG Binder: Mapped property: id -> USER_ID, type: long
15:31:54,968 DEBUG Binder: Mapped property: version -> VERSION, type: integer
15:31:55,062 DEBUG Binder: Mapped property: firstname -> FIRSTNAME, type: string
15:31:55,062 DEBUG Binder: Mapped property: lastname -> LASTNAME, type: string
15:31:55,062 DEBUG Binder: Mapped property: username -> USERNAME, type: string
15:31:55,062 DEBUG Binder: Mapped property: password -> PASSWORD, type: string
15:31:55,062 DEBUG Binder: Mapped property: email -> EMAIL, type: string
15:31:55,062 DEBUG Binder: Mapped property: ranking -> RANKING, type: integer
15:31:55,062 DEBUG Binder: Mapped property: admin -> IS_ADMIN, type: boolean
15:31:55,062 DEBUG Binder: Mapped property: created -> CREATED, type: timestamp
15:31:55,078 DEBUG Binder: Mapped property: defaultBillingDetails -> DEFAULT_BILLING_DETAILS_ID, type: org.hibernate.auction.model.BillingDetails
15:31:55,109 DEBUG Binder: Mapped property: street -> STREET, type: string
15:31:55,109 DEBUG Binder: Mapped property: zipcode -> ZIPCODE, type: string
15:31:55,109 DEBUG Binder: Mapped property: city -> CITY, type: string
15:31:55,328 DEBUG Binder: Mapped property: address -> STREET, ZIPCODE, CITY, type: org.hibernate.auction.model.Address
15:31:55,343 DEBUG Binder: Mapped property: items, type: java.util.Set
15:31:55,343 DEBUG Binder: Mapped property: billingDetails, type: java.util.Set
15:31:55,343 DEBUG Configuration: null<-org.dom4j.tree.DefaultAttribute@ee7a14 [Attribute: name resource value "org/hibernate/auction/model/Item.hbm.xml"]
15:31:55,359 INFO Configuration: Mapping resource: org/hibernate/auction/model/Item.hbm.xml
15:31:55,359 DEBUG DTDEntityResolver: trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
15:31:55,359 DEBUG DTDEntityResolver: found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
15:31:55,406 INFO Binder: Mapping class: org.hibernate.auction.model.Item -> ITEM
15:31:55,406 DEBUG Binder: Mapped property: id -> ITEM_ID, type: long
15:31:55,406 DEBUG Binder: Mapped property: version -> VERSION, type: integer
15:31:55,406 DEBUG Binder: Mapped property: name -> NAME, type: string
15:31:55,406 DEBUG Binder: Mapped property: description -> DESCRIPTION, type: string
15:31:55,406 DEBUG Binder: Mapped property: initialPrice -> INITIAL_PRICE, INITIAL_PRICE_CURRENCY, type: org.hibernate.auction.persistence.MonetaryAmountCompositeUserType
15:31:55,406 DEBUG Binder: Mapped property: reservePrice -> RESERVE_PRICE, RESERVE_PRICE_CURRENCY, type: org.hibernate.auction.persistence.MonetaryAmountCompositeUserType
15:31:55,406 DEBUG Binder: Mapped property: startDate -> START_DATE, type: timestamp
15:31:55,406 DEBUG Binder: Mapped property: endDate -> END_DATE, type: timestamp
15:31:55,421 DEBUG Binder: Mapped property: state -> STATE, type: org.hibernate.auction.persistence.ItemStateUserType
15:31:55,421 DEBUG Binder: Mapped property: approvalDatetime -> APPROVAL_DATETIME, type: timestamp
15:31:55,421 DEBUG Binder: Mapped property: created -> CREATED, type: timestamp
15:31:55,421 DEBUG Binder: Mapped property: approvedBy -> APPROVED_BY_USER_ID, type: org.hibernate.auction.model.User
15:31:55,421 DEBUG Binder: Mapped property: seller -> SELLER_ID, type: org.hibernate.auction.model.User
15:31:55,421 DEBUG Binder: Mapped property: successfulBid -> SUCCESSFUL_BID_ID, type: org.hibernate.auction.model.Bid
15:31:55,421 INFO Binder: Mapping collection: org.hibernate.auction.model.Item.categories -> CATEGORY_ITEM
15:31:55,421 DEBUG Binder: Mapped property: categories, type: java.util.Set
15:31:55,421 DEBUG Binder: Mapped property: bids, type: java.util.Collection
15:31:55,421 DEBUG Configuration: null<-org.dom4j.tree.DefaultAttribute@b8bef7 [Attribute: name resource value "org/hibernate/auction/model/Category.hbm.xml"]
15:31:55,421 INFO Configuration: Mapping resource: org/hibernate/auction/model/Category.hbm.xml
15:31:55,421 DEBUG DTDEntityResolver: trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
15:31:55,421 DEBUG DTDEntityResolver: found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
15:31:55,437 INFO Binder: Mapping class: org.hibernate.auction.model.Category -> CATEGORY
15:31:55,437 DEBUG Binder: Mapped property: id -> CATEGORY_ID, type: long
15:31:55,437 DEBUG Binder: Mapped property: version -> VERSION, type: integer
15:31:55,437 DEBUG Binder: Mapped property: name -> NAME, type: string
15:31:55,437 DEBUG Binder: Mapped property: created -> CREATED, type: timestamp
15:31:55,453 DEBUG Binder: Mapped property: parentCategory -> PARENT_CATEGORY_ID, type: org.hibernate.auction.model.Category
15:31:55,453 DEBUG Binder: Mapped property: childCategories, type: java.util.Set
15:31:55,453 INFO Binder: Mapping collection: org.hibernate.auction.model.Category.items -> CATEGORY_ITEM
15:31:55,453 DEBUG Binder: Mapped property: items, type: java.util.Set
15:31:55,453 DEBUG Configuration: null<-org.dom4j.tree.DefaultAttribute@c1f10e [Attribute: name resource value "org/hibernate/auction/model/Bid.hbm.xml"]
15:31:55,453 INFO Configuration: Mapping resource: org/hibernate/auction/model/Bid.hbm.xml
15:31:55,453 DEBUG DTDEntityResolver: trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
15:31:55,453 DEBUG DTDEntityResolver: found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
15:31:55,468 INFO Binder: Mapping class: org.hibernate.auction.model.Bid -> BID
15:31:55,468 DEBUG Binder: Mapped property: id -> BID_ID, type: long
15:31:55,468 DEBUG Binder: Mapped property: amount -> AMOUNT, AMOUNT_CURRENCY, type: org.hibernate.auction.persistence.MonetaryAmountCompositeUserType
15:31:55,468 DEBUG Binder: Mapped property: created -> CREATED, type: timestamp
15:31:55,468 DEBUG Binder: Mapped property: item -> ITEM_ID, type: org.hibernate.auction.model.Item
15:31:55,468 DEBUG Binder: Mapped property: bidder -> BIDDER_ID, type: org.hibernate.auction.model.User
15:31:55,468 DEBUG Configuration: null<-org.dom4j.tree.DefaultAttribute@1342ba4 [Attribute: name resource value "org/hibernate/auction/model/BillingDetails.hbm.xml"]
15:31:55,468 INFO Configuration: Mapping resource: org/hibernate/auction/model/BillingDetails.hbm.xml
15:31:55,484 DEBUG DTDEntityResolver: trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
15:31:55,484 DEBUG DTDEntityResolver: found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
15:31:55,500 INFO Binder: Mapping class: org.hibernate.auction.model.BillingDetails -> BILLING_DETAILS
15:31:55,500 DEBUG Binder: Mapped property: id -> BILLING_DETAILS_ID, type: long
15:31:55,500 DEBUG Binder: Mapped property: version -> VERSION, type: integer
15:31:55,500 DEBUG Binder: Mapped property: ownerName -> OWNER_NAME, type: string
15:31:55,500 DEBUG Binder: Mapped property: created -> CREATED, type: timestamp
15:31:55,500 DEBUG Binder: Mapped property: user -> USER_ID, type: org.hibernate.auction.model.User
15:31:55,593 INFO Binder: Mapping joined-subclass: org.hibernate.auction.model.CreditCard -> CREDIT_CARD
15:31:55,593 DEBUG Binder: Mapped property: type -> CC_TYPE, type: org.hibernate.auction.persistence.CreditCardTypeUserType
15:31:55,593 DEBUG Binder: Mapped property: number -> CC_NUMBER, type: string
15:31:55,593 DEBUG Binder: Mapped property: expMonth -> EXP_MONTH, type: string
15:31:55,593 DEBUG Binder: Mapped property: expYear -> EXP_YEAR, type: string
15:31:55,593 INFO Binder: Mapping joined-subclass: org.hibernate.auction.model.BankAccount -> BANK_ACCOUNT
15:31:55,593 DEBUG Binder: Mapped property: number -> ACCOUNT_NUMBER, type: string
15:31:55,593 DEBUG Binder: Mapped property: bankName -> BANK_NAME, type: string
15:31:55,593 DEBUG Binder: Mapped property: bankSwift -> BANK_SWIFT, type: string
15:31:55,593 DEBUG Configuration: null<-org.dom4j.tree.DefaultAttribute@19b5217 [Attribute: name resource value "org/hibernate/auction/model/Comment.hbm.xml"]
15:31:55,593 INFO Configuration: Mapping resource: org/hibernate/auction/model/Comment.hbm.xml
15:31:55,640 DEBUG DTDEntityResolver: trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
15:31:55,640 DEBUG DTDEntityResolver: found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
15:31:55,656 INFO Binder: Mapping class: org.hibernate.auction.model.Comment -> COMMENTS
15:31:55,656 DEBUG Binder: Mapped property: id -> COMMENT_ID, type: long
15:31:55,656 DEBUG Binder: Mapped property: version -> VERSION, type: integer
15:31:55,656 DEBUG Binder: Mapped property: rating -> RATING, type: org.hibernate.auction.persistence.RatingUserType
15:31:55,656 DEBUG Binder: Mapped property: text -> COMMENT_TEXT, type: string
15:31:55,656 DEBUG Binder: Mapped property: item -> ITEM_ID, type: org.hibernate.auction.model.Item
15:31:55,656 DEBUG Binder: Mapped property: fromUser -> FROM_USER_ID, type: org.hibernate.auction.model.User
15:31:55,656 DEBUG Configuration: null<-org.dom4j.tree.DefaultAttribute@7808b9 [Attribute: name resource value "org/hibernate/auction/persistence/audit/AuditLogRecord.hbm.xml"]
15:31:55,656 INFO Configuration: Mapping resource: org/hibernate/auction/persistence/audit/AuditLogRecord.hbm.xml
15:31:55,656 DEBUG DTDEntityResolver: trying to locate
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath under net/sf/hibernate/
15:31:55,656 DEBUG DTDEntityResolver: found
http://hibernate.sourceforge.net/hibern ... ng-2.0.dtd in classpath
15:31:55,671 INFO Binder: Mapping class: org.hibernate.auction.persistence.audit.AuditLogRecord -> AUDIT_LOG
15:31:55,671 DEBUG Binder: Mapped property: message -> MESSAGE, type: string
15:31:55,671 DEBUG Binder: Mapped property: entityId -> ENTITY_ID, type: long
15:31:55,671 DEBUG Binder: Mapped property: entityClass -> ENTITY_CLASS, type: class
15:31:55,671 DEBUG Binder: Mapped property: userId -> USER_ID, type: long
15:31:55,671 DEBUG Binder: Mapped property: created -> CREATED, type: timestamp
15:31:55,671 INFO Configuration: Configured SessionFactory: null
15:31:55,671 DEBUG Configuration: properties: {show_sql=false, java.vendor=Sun Microsystems Inc., hibernate.connection.url=jdbc:mysql://127.0.0.1/hia, os.name=Windows XP, sun.boot.class.path=C:\java\j2sdk1.4.2_05\jre\lib\rt.jar;C:\java\j2sdk1.4.2_05\jre\lib\i18n.jar;C:\java\j2sdk1.4.2_05\jre\lib\sunrsasign.jar;C:\java\j2sdk1.4.2_05\jre\lib\jsse.jar;C:\java\j2sdk1.4.2_05\jre\lib\jce.jar;C:\java\j2sdk1.4.2_05\jre\lib\charsets.jar;C:\java\j2sdk1.4.2_05\jre\classes, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.4.2_05-b04, hibernate.cache.use_minimal_puts=false, hibernate.cache.provider_class=net.sf.ehcache.hibernate.Provider, user.name=Administrator, connection.driver_class=org.gjt.mm.mysql.Driver, user.language=en, sun.boot.library.path=C:\java\j2sdk1.4.2_05\jre\bin, dialect=net.sf.hibernate.dialect.MySQLDialect, java.version=1.4.2_05, user.timezone=America/Los_Angeles, sun.arch.data.model=32, java.endorsed.dirs=C:\java\j2sdk1.4.2_05\jre\lib\endorsed, sun.cpu.isalist=pentium i486 i386, file.encoding.pkg=sun.io, file.separator=\, cache.use_query_cache=false, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=48.0, cache.use_minimal_puts=false, user.country=US, connection.url=jdbc:mysql://127.0.0.1/hia, java.home=C:\java\j2sdk1.4.2_05\jre, java.vm.info=mixed mode, os.version=5.1, path.separator=;, connection.password=losixx, java.vm.version=1.4.2_05-b04, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, hibernate.connection.password=losixx, user.variant=, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, hibernate.connection.username=root, user.home=C:\Documents and Settings\Administrator, idea.launcher.library=C:\java\IntelliJ-IDEA-4.5\bin\breakgen.dll, java.specification.vendor=Sun Microsystems Inc., java.library.path=C:\java\j2sdk1.4.2_05\bin;.;C:\WINDOWS\System32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\cvsnt;C:\java\bin;C:\java\apache-ant-1.6.2\bin;C:\mysql\bin;C:\java\maven-1.0\bin, java.vendor.url=http://java.sun.com/, hibernate.connection.driver_class=org.gjt.mm.mysql.Driver, connection.username=root, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:\java\j2sdk1.4.2_05\jre\lib\charsets.jar;C:\java\j2sdk1.4.2_05\jre\lib\jce.jar;C:\java\j2sdk1.4.2_05\jre\lib\jsse.jar;C:\java\j2sdk1.4.2_05\jre\lib\plugin.jar;C:\java\j2sdk1.4.2_05\jre\lib\rt.jar;C:\java\j2sdk1.4.2_05\jre\lib\sunrsasign.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\dnsns.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\ldapsec.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\localedata.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\sunjce_provider.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\build\standalone;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\dom4j-1.4.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\hsqldb.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\commons-collections-2.1.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\commons-logging-1.0.4.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\ejb-api.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\odmg-3.0.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\log4j-1.2.8.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\c3p0-0.8.4.5.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\ant-junit-1.6.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\jsdk23.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\commons-lang-1.0.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\junit-3.8.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\hibernate-2.1.5.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\jta-api.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\cglib-full-2.0.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\ehcache-0.9.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\mysql-connector-java-3.0.11-stable-bin.jar;C:\java\IntelliJ-IDEA-4.5\lib\idea_rt.jar, idea.launcher.port=7535, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, sun.cpu.endian=little, sun.os.patch.level=Service Pack 1, hibernate.cache.use_query_cache=false, connection.pool_size=1, java.io.tmpdir=C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\java\j2sdk1.4.2_05\jre\lib\ext, user.dir=C:\Documents and Settings\Administrator\Desktop\caveatemptor, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, cache.provider_class=net.sf.ehcache.hibernate.Provider, file.encoding=windows-1252, java.specification.version=1.4, hibernate.show_sql=false, hibernate.connection.pool_size=1}
15:31:55,703 INFO Configuration: processing one-to-many association mappings
15:31:55,703 DEBUG Binder: Second pass for collection: org.hibernate.auction.model.User.items
15:31:55,703 INFO Binder: Mapping collection: org.hibernate.auction.model.User.items -> ITEM
15:31:55,703 DEBUG Binder: Mapped collection key: SELLER_ID, one-to-many: org.hibernate.auction.model.Item
15:31:55,703 DEBUG Binder: Second pass for collection: org.hibernate.auction.model.User.billingDetails
15:31:55,703 INFO Binder: Mapping collection: org.hibernate.auction.model.User.billingDetails -> BILLING_DETAILS
15:31:55,703 DEBUG Binder: Mapped collection key: USER_ID, one-to-many: org.hibernate.auction.model.BillingDetails
15:31:55,703 DEBUG Binder: Second pass for collection: org.hibernate.auction.model.Item.categories
15:31:55,703 DEBUG Binder: Mapped collection key: ITEM_ID, element: CATEGORY_ID, type: org.hibernate.auction.model.Category
15:31:55,703 DEBUG Binder: Second pass for collection: org.hibernate.auction.model.Item.bids
15:31:55,703 INFO Binder: Mapping collection: org.hibernate.auction.model.Item.bids -> BID
15:31:55,703 DEBUG Binder: Mapped collection key: ITEM_ID, one-to-many: org.hibernate.auction.model.Bid
15:31:55,703 DEBUG Binder: Second pass for collection: org.hibernate.auction.model.Category.childCategories
15:31:55,703 INFO Binder: Mapping collection: org.hibernate.auction.model.Category.childCategories -> CATEGORY
15:31:55,703 DEBUG Binder: Mapped collection key: PARENT_CATEGORY_ID, one-to-many: org.hibernate.auction.model.Category
15:31:55,703 DEBUG Binder: Second pass for collection: org.hibernate.auction.model.Category.items
15:31:55,703 DEBUG Binder: Mapped collection key: CATEGORY_ID, element: ITEM_ID, type: org.hibernate.auction.model.Item
15:31:55,703 INFO Configuration: processing one-to-one association property references
15:31:55,703 INFO Configuration: processing foreign key constraints
15:31:55,703 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Item
15:31:55,703 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:55,703 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.BillingDetails
15:31:55,703 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:55,703 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.BillingDetails
15:31:55,703 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Category
15:31:55,718 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:55,718 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:55,718 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Bid
15:31:55,718 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.BillingDetails
15:31:55,718 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:55,718 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Item
15:31:55,718 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Category
15:31:55,718 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Item
15:31:55,750 INFO Dialect: Using dialect: net.sf.hibernate.dialect.MySQLDialect
15:31:55,750 INFO SettingsFactory: Use outer join fetching: true
15:31:55,750 INFO DriverManagerConnectionProvider: Using Hibernate built-in connection pool (not for production use!)
15:31:55,750 INFO DriverManagerConnectionProvider: Hibernate connection pool size: 1
15:31:55,765 INFO DriverManagerConnectionProvider: using driver: org.gjt.mm.mysql.Driver at URL: jdbc:mysql://127.0.0.1/hia
15:31:55,765 INFO DriverManagerConnectionProvider: connection properties: {user=root, password=losixx}
15:31:55,781 INFO TransactionManagerLookupFactory: No TransactionManagerLookup configured (in JTA environment, use of process level read-write cache is not recommended)
15:31:55,781 DEBUG DriverManagerConnectionProvider: total checked-out connections: 0
15:31:55,781 DEBUG DriverManagerConnectionProvider: opening new JDBC connection
15:31:56,031 DEBUG DriverManagerConnectionProvider: created connection to: jdbc:mysql://127.0.0.1/hia, Isolation Level: 4
15:31:56,031 DEBUG DriverManagerConnectionProvider: returning connection to pool, pool size: 1
15:31:56,031 INFO SettingsFactory: Use scrollable result sets: true
15:31:56,031 INFO SettingsFactory: Use JDBC3 getGeneratedKeys(): true
15:31:56,031 INFO SettingsFactory: Optimize cache for minimal puts: false
15:31:56,031 INFO SettingsFactory: Query language substitutions: {}
15:31:56,031 INFO SettingsFactory: cache provider: net.sf.ehcache.hibernate.Provider
15:31:56,046 INFO Configuration: instantiating and configuring caches
15:31:56,109 INFO SessionFactoryImpl: building session factory
15:31:56,109 DEBUG SessionFactoryImpl: instantiating session factory with properties: {show_sql=false, java.vendor=Sun Microsystems Inc., hibernate.connection.url=jdbc:mysql://127.0.0.1/hia, os.name=Windows XP, sun.boot.class.path=C:\java\j2sdk1.4.2_05\jre\lib\rt.jar;C:\java\j2sdk1.4.2_05\jre\lib\i18n.jar;C:\java\j2sdk1.4.2_05\jre\lib\sunrsasign.jar;C:\java\j2sdk1.4.2_05\jre\lib\jsse.jar;C:\java\j2sdk1.4.2_05\jre\lib\jce.jar;C:\java\j2sdk1.4.2_05\jre\lib\charsets.jar;C:\java\j2sdk1.4.2_05\jre\classes, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.4.2_05-b04, hibernate.cache.use_minimal_puts=false, hibernate.cache.provider_class=net.sf.ehcache.hibernate.Provider, user.name=Administrator, connection.driver_class=org.gjt.mm.mysql.Driver, user.language=en, sun.boot.library.path=C:\java\j2sdk1.4.2_05\jre\bin, dialect=net.sf.hibernate.dialect.MySQLDialect, java.version=1.4.2_05, user.timezone=America/Los_Angeles, sun.arch.data.model=32, java.endorsed.dirs=C:\java\j2sdk1.4.2_05\jre\lib\endorsed, sun.cpu.isalist=pentium i486 i386, file.encoding.pkg=sun.io, file.separator=\, cache.use_query_cache=false, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=48.0, cache.use_minimal_puts=false, user.country=US, connection.url=jdbc:mysql://127.0.0.1/hia, java.home=C:\java\j2sdk1.4.2_05\jre, java.vm.info=mixed mode, os.version=5.1, path.separator=;, connection.password=losixx, java.vm.version=1.4.2_05-b04, java.util.prefs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, hibernate.connection.password=losixx, user.variant=, java.awt.printerjob=sun.awt.windows.WPrinterJob, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, hibernate.connection.username=root, user.home=C:\Documents and Settings\Administrator, idea.launcher.library=C:\java\IntelliJ-IDEA-4.5\bin\breakgen.dll, java.specification.vendor=Sun Microsystems Inc., java.library.path=C:\java\j2sdk1.4.2_05\bin;.;C:\WINDOWS\System32;C:\WINDOWS;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\cvsnt;C:\java\bin;C:\java\apache-ant-1.6.2\bin;C:\mysql\bin;C:\java\maven-1.0\bin, java.vendor.url=http://java.sun.com/, hibernate.connection.driver_class=org.gjt.mm.mysql.Driver, connection.username=root, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=C:\java\j2sdk1.4.2_05\jre\lib\charsets.jar;C:\java\j2sdk1.4.2_05\jre\lib\jce.jar;C:\java\j2sdk1.4.2_05\jre\lib\jsse.jar;C:\java\j2sdk1.4.2_05\jre\lib\plugin.jar;C:\java\j2sdk1.4.2_05\jre\lib\rt.jar;C:\java\j2sdk1.4.2_05\jre\lib\sunrsasign.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\dnsns.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\ldapsec.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\localedata.jar;C:\java\j2sdk1.4.2_05\jre\lib\ext\sunjce_provider.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\build\standalone;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\dom4j-1.4.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\hsqldb.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\commons-collections-2.1.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\commons-logging-1.0.4.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\ejb-api.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\odmg-3.0.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\log4j-1.2.8.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\c3p0-0.8.4.5.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\ant-junit-1.6.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\jsdk23.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\commons-lang-1.0.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\junit-3.8.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\hibernate-2.1.5.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\jta-api.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\cglib-full-2.0.1.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\ehcache-0.9.jar;C:\Documents and Settings\Administrator\Desktop\caveatemptor\lib\mysql-connector-java-3.0.11-stable-bin.jar;C:\java\IntelliJ-IDEA-4.5\lib\idea_rt.jar, idea.launcher.port=7535, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, sun.cpu.endian=little, sun.os.patch.level=Service Pack 1, hibernate.cache.use_query_cache=false, connection.pool_size=1, java.io.tmpdir=C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, os.arch=x86, java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=C:\java\j2sdk1.4.2_05\jre\lib\ext, user.dir=C:\Documents and Settings\Administrator\Desktop\caveatemptor, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, cache.provider_class=net.sf.ehcache.hibernate.Provider, file.encoding=windows-1252, java.specification.version=1.4, hibernate.show_sql=false, hibernate.connection.pool_size=1}
15:31:57,000 DEBUG SessionFactoryObjectFactory: initializing class SessionFactoryObjectFactory
15:31:57,000 DEBUG SessionFactoryObjectFactory: registered: 40288083fe363acb00fe363acf480000 (unnamed)
15:31:57,000 INFO SessionFactoryObjectFactory: Not binding factory to JNDI, no JNDI name configured
15:31:57,000 DEBUG SessionFactoryImpl: instantiated session factory
15:31:57,000 INFO Dialect: Using dialect: net.sf.hibernate.dialect.MySQLDialect
15:31:57,000 INFO Configuration: processing one-to-many association mappings
15:31:57,031 INFO Configuration: processing one-to-one association property references
15:31:57,031 INFO Configuration: processing foreign key constraints
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Item
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.BillingDetails
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.BillingDetails
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Category
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Bid
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.BillingDetails
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Item
15:31:57,031 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Category
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Item
15:31:57,046 INFO Configuration: processing one-to-many association mappings
15:31:57,046 INFO Configuration: processing one-to-one association property references
15:31:57,046 INFO Configuration: processing foreign key constraints
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Item
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.BillingDetails
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.BillingDetails
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Category
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Bid
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.BillingDetails
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.User
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Item
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Category
15:31:57,046 DEBUG Configuration: resolving reference to class: org.hibernate.auction.model.Item
15:31:57,046 INFO SchemaExport: Running hbm2ddl schema export
15:31:57,046 INFO SchemaExport: exporting generated schema to database
15:31:57,046 INFO DriverManagerConnectionProvider: Using Hibernate built-in connection pool (not for production use!)
15:31:57,062 INFO DriverManagerConnectionProvider: Hibernate connection pool size: 1
15:31:57,062 INFO DriverManagerConnectionProvider: using driver: org.gjt.mm.mysql.Driver at URL: jdbc:mysql://127.0.0.1/hia
15:31:57,062 INFO DriverManagerConnectionProvider: connection properties: {user=root, password=losixx}
15:31:57,062 DEBUG DriverManagerConnectionProvider: total checked-out connections: 0
15:31:57,062 DEBUG DriverManagerConnectionProvider: opening new JDBC connection
15:31:57,062 DEBUG DriverManagerConnectionProvider: created connection to: jdbc:mysql://127.0.0.1/hia, Isolation Level: 4
15:31:58,781 INFO SchemaExport: schema export complete
15:31:58,781 DEBUG DriverManagerConnectionProvider: returning connection to pool, pool size: 1
15:31:58,781 INFO DriverManagerConnectionProvider: cleaning up connection pool: jdbc:mysql://127.0.0.1/hia
Running test...
15:31:58,828 DEBUG SessionImpl: opened session
15:31:58,843 DEBUG JDBCTransaction: begin
15:31:58,843 DEBUG DriverManagerConnectionProvider: total checked-out connections: 0
15:31:58,843 DEBUG DriverManagerConnectionProvider: using pooled JDBC connection, pool size: 0
15:31:58,843 DEBUG JDBCTransaction: current autocommit status:false
15:31:58,843 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:58,843 DEBUG SessionImpl: saving [org.hibernate.auction.model.Category#<null>]
15:31:58,843 DEBUG SessionImpl: executing insertions
15:31:58,843 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Category
15:31:58,859 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Category
15:31:58,859 DEBUG Versioning: using initial version: 0
15:31:58,859 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Category.childCategories
15:31:58,859 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Category.items
15:31:58,875 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Category (native id)
15:31:58,875 DEBUG EntityPersister: Version: 0
15:31:58,875 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:58,875 DEBUG SQL: insert into CATEGORY (VERSION, NAME, CREATED, PARENT_CATEGORY_ID) values (?, ?, ?, ?)
15:31:58,875 DEBUG BatcherImpl: preparing statement
15:31:58,890 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Category#<null>]
15:31:58,921 DEBUG AbstractEntityPersister: Natively generated identity: 1
15:31:58,921 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:58,921 DEBUG BatcherImpl: closing statement
15:31:58,937 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Category
15:31:58,937 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Category.childCategories
15:31:58,937 DEBUG Cascades: cascading to saveOrUpdate()
15:31:58,937 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:58,937 DEBUG SessionImpl: saving [org.hibernate.auction.model.Category#<null>]
15:31:58,937 DEBUG SessionImpl: executing insertions
15:31:58,937 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Category
15:31:58,937 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Category
15:31:58,937 DEBUG Versioning: using initial version: 0
15:31:58,937 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Category.childCategories
15:31:58,937 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Category.items
15:31:58,937 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Category (native id)
15:31:58,937 DEBUG EntityPersister: Version: 0
15:31:58,937 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:58,937 DEBUG SQL: insert into CATEGORY (VERSION, NAME, CREATED, PARENT_CATEGORY_ID) values (?, ?, ?, ?)
15:31:58,937 DEBUG BatcherImpl: preparing statement
15:31:58,937 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Category#<null>]
15:31:58,937 DEBUG AbstractEntityPersister: Natively generated identity: 2
15:31:58,937 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:58,937 DEBUG BatcherImpl: closing statement
15:31:58,937 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Category
15:31:58,937 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Category.childCategories
15:31:58,937 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Category
15:31:58,968 DEBUG Cascades: cascading to saveOrUpdate()
15:31:58,968 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:58,968 DEBUG SessionImpl: saving [org.hibernate.auction.model.Category#<null>]
15:31:58,968 DEBUG SessionImpl: executing insertions
15:31:58,968 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Category
15:31:58,968 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Category
15:31:58,968 DEBUG Versioning: using initial version: 0
15:31:58,968 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Category.childCategories
15:31:58,968 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Category.items
15:31:58,968 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Category (native id)
15:31:58,968 DEBUG EntityPersister: Version: 0
15:31:58,968 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:58,968 DEBUG SQL: insert into CATEGORY (VERSION, NAME, CREATED, PARENT_CATEGORY_ID) values (?, ?, ?, ?)
15:31:58,968 DEBUG BatcherImpl: preparing statement
15:31:58,968 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Category#<null>]
15:31:58,968 DEBUG AbstractEntityPersister: Natively generated identity: 3
15:31:58,968 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:58,968 DEBUG BatcherImpl: closing statement
15:31:58,968 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Category
15:31:58,968 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Category.childCategories
15:31:58,968 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Category
15:31:58,968 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Category
15:31:58,968 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:58,968 DEBUG SessionImpl: saving [org.hibernate.auction.model.User#<null>]
15:31:58,968 DEBUG SessionImpl: executing insertions
15:31:58,968 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.User
15:31:58,968 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.User
15:31:58,968 DEBUG Versioning: using initial version: 0
15:31:58,984 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.User.items
15:31:58,984 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.User.billingDetails
15:31:58,984 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.User (native id)
15:31:58,984 DEBUG EntityPersister: Version: 0
15:31:58,984 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:58,984 DEBUG SQL: insert into USERS (VERSION, FIRSTNAME, LASTNAME, USERNAME, `PASSWORD`, EMAIL, RANKING, IS_ADMIN, CREATED, DEFAULT_BILLING_DETAILS_ID, STREET, ZIPCODE, CITY) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
15:31:58,984 DEBUG BatcherImpl: preparing statement
15:31:59,000 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.User#<null>]
15:31:59,000 DEBUG AbstractEntityPersister: Natively generated identity: 1
15:31:59,000 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,000 DEBUG BatcherImpl: closing statement
15:31:59,031 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.User
15:31:59,031 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.User.billingDetails
15:31:59,031 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.User
15:31:59,031 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,031 DEBUG SessionImpl: saving [org.hibernate.auction.model.User#<null>]
15:31:59,031 DEBUG SessionImpl: executing insertions
15:31:59,031 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.User
15:31:59,031 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.User
15:31:59,031 DEBUG Versioning: using initial version: 0
15:31:59,031 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.User.items
15:31:59,031 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.User.billingDetails
15:31:59,031 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.User (native id)
15:31:59,046 DEBUG EntityPersister: Version: 0
15:31:59,046 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,046 DEBUG SQL: insert into USERS (VERSION, FIRSTNAME, LASTNAME, USERNAME, `PASSWORD`, EMAIL, RANKING, IS_ADMIN, CREATED, DEFAULT_BILLING_DETAILS_ID, STREET, ZIPCODE, CITY) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
15:31:59,046 DEBUG BatcherImpl: preparing statement
15:31:59,046 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.User#<null>]
15:31:59,062 DEBUG AbstractEntityPersister: Natively generated identity: 2
15:31:59,062 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,062 DEBUG BatcherImpl: closing statement
15:31:59,062 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.User
15:31:59,062 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.User.billingDetails
15:31:59,062 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.User
15:31:59,062 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,062 DEBUG SessionImpl: saving [org.hibernate.auction.model.User#<null>]
15:31:59,062 DEBUG SessionImpl: executing insertions
15:31:59,062 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.User
15:31:59,062 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.User
15:31:59,062 DEBUG Versioning: using initial version: 0
15:31:59,062 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.User.items
15:31:59,062 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.User.billingDetails
15:31:59,078 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.User (native id)
15:31:59,093 DEBUG EntityPersister: Version: 0
15:31:59,093 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,093 DEBUG SQL: insert into USERS (VERSION, FIRSTNAME, LASTNAME, USERNAME, `PASSWORD`, EMAIL, RANKING, IS_ADMIN, CREATED, DEFAULT_BILLING_DETAILS_ID, STREET, ZIPCODE, CITY) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
15:31:59,093 DEBUG BatcherImpl: preparing statement
15:31:59,109 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.User#<null>]
15:31:59,109 DEBUG AbstractEntityPersister: Natively generated identity: 3
15:31:59,109 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,109 DEBUG BatcherImpl: closing statement
15:31:59,109 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.User
15:31:59,109 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.User.billingDetails
15:31:59,109 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.User
15:31:59,109 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,109 DEBUG SessionImpl: saving [org.hibernate.auction.model.Item#<null>]
15:31:59,109 DEBUG SessionImpl: executing insertions
15:31:59,109 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,125 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,125 DEBUG Versioning: using initial version: 0
15:31:59,125 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Item.categories
15:31:59,125 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Item.bids
15:31:59,125 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Item (native id)
15:31:59,125 DEBUG EntityPersister: Version: 0
15:31:59,125 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,125 DEBUG SQL: insert into ITEM (VERSION, NAME, DESCRIPTION, INITIAL_PRICE, INITIAL_PRICE_CURRENCY, RESERVE_PRICE, RESERVE_PRICE_CURRENCY, START_DATE, END_DATE, STATE, APPROVAL_DATETIME, CREATED, APPROVED_BY_USER_ID, SELLER_ID, SUCCESSFUL_BID_ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
15:31:59,125 DEBUG BatcherImpl: preparing statement
15:31:59,125 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Item#<null>]
15:31:59,125 DEBUG AbstractEntityPersister: Natively generated identity: 1
15:31:59,125 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,125 DEBUG BatcherImpl: closing statement
15:31:59,125 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,125 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Item.bids
15:31:59,125 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,125 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,125 DEBUG SessionImpl: saving [org.hibernate.auction.model.Item#<null>]
15:31:59,125 DEBUG SessionImpl: executing insertions
15:31:59,125 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,125 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,125 DEBUG Versioning: using initial version: 0
15:31:59,125 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Item.categories
15:31:59,125 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Item.bids
15:31:59,125 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Item (native id)
15:31:59,125 DEBUG EntityPersister: Version: 0
15:31:59,125 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,125 DEBUG SQL: insert into ITEM (VERSION, NAME, DESCRIPTION, INITIAL_PRICE, INITIAL_PRICE_CURRENCY, RESERVE_PRICE, RESERVE_PRICE_CURRENCY, START_DATE, END_DATE, STATE, APPROVAL_DATETIME, CREATED, APPROVED_BY_USER_ID, SELLER_ID, SUCCESSFUL_BID_ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
15:31:59,125 DEBUG BatcherImpl: preparing statement
15:31:59,125 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Item#<null>]
15:31:59,125 DEBUG AbstractEntityPersister: Natively generated identity: 2
15:31:59,125 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,125 DEBUG BatcherImpl: closing statement
15:31:59,125 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,125 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Item.bids
15:31:59,125 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,140 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,140 DEBUG SessionImpl: saving [org.hibernate.auction.model.Item#<null>]
15:31:59,140 DEBUG SessionImpl: executing insertions
15:31:59,140 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,140 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,140 DEBUG Versioning: using initial version: 0
15:31:59,140 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Item.categories
15:31:59,140 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Item.bids
15:31:59,140 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Item (native id)
15:31:59,140 DEBUG EntityPersister: Version: 0
15:31:59,140 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,140 DEBUG SQL: insert into ITEM (VERSION, NAME, DESCRIPTION, INITIAL_PRICE, INITIAL_PRICE_CURRENCY, RESERVE_PRICE, RESERVE_PRICE_CURRENCY, START_DATE, END_DATE, STATE, APPROVAL_DATETIME, CREATED, APPROVED_BY_USER_ID, SELLER_ID, SUCCESSFUL_BID_ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
15:31:59,140 DEBUG BatcherImpl: preparing statement
15:31:59,156 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Item#<null>]
15:31:59,156 DEBUG AbstractEntityPersister: Natively generated identity: 3
15:31:59,156 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,156 DEBUG BatcherImpl: closing statement
15:31:59,156 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,156 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Item.bids
15:31:59,156 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,156 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,156 DEBUG SessionImpl: saving [org.hibernate.auction.model.Item#<null>]
15:31:59,156 DEBUG SessionImpl: executing insertions
15:31:59,156 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,156 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,171 DEBUG Versioning: using initial version: 0
15:31:59,171 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Item.categories
15:31:59,171 DEBUG WrapVisitor: Wrapped collection in role: org.hibernate.auction.model.Item.bids
15:31:59,171 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Item (native id)
15:31:59,171 DEBUG EntityPersister: Version: 0
15:31:59,171 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,171 DEBUG SQL: insert into ITEM (VERSION, NAME, DESCRIPTION, INITIAL_PRICE, INITIAL_PRICE_CURRENCY, RESERVE_PRICE, RESERVE_PRICE_CURRENCY, START_DATE, END_DATE, STATE, APPROVAL_DATETIME, CREATED, APPROVED_BY_USER_ID, SELLER_ID, SUCCESSFUL_BID_ID) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
15:31:59,171 DEBUG BatcherImpl: preparing statement
15:31:59,171 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Item#<null>]
15:31:59,171 DEBUG AbstractEntityPersister: Natively generated identity: 4
15:31:59,171 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,171 DEBUG BatcherImpl: closing statement
15:31:59,171 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,171 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Item.bids
15:31:59,171 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,171 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,171 DEBUG SessionImpl: saving [org.hibernate.auction.model.Comment#<null>]
15:31:59,171 DEBUG SessionImpl: executing insertions
15:31:59,171 DEBUG Versioning: using initial version: 0
15:31:59,171 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Comment (native id)
15:31:59,171 DEBUG EntityPersister: Version: 0
15:31:59,171 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,171 DEBUG SQL: insert into COMMENTS (VERSION, RATING, COMMENT_TEXT, ITEM_ID, FROM_USER_ID) values (?, ?, ?, ?, ?)
15:31:59,171 DEBUG BatcherImpl: preparing statement
15:31:59,171 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Comment#<null>]
15:31:59,171 DEBUG AbstractEntityPersister: Natively generated identity: 1
15:31:59,203 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,203 DEBUG BatcherImpl: closing statement
15:31:59,203 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,203 DEBUG SessionImpl: saving [org.hibernate.auction.model.Comment#<null>]
15:31:59,203 DEBUG SessionImpl: executing insertions
15:31:59,203 DEBUG Versioning: using initial version: 0
15:31:59,203 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Comment (native id)
15:31:59,203 DEBUG EntityPersister: Version: 0
15:31:59,203 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,203 DEBUG SQL: insert into COMMENTS (VERSION, RATING, COMMENT_TEXT, ITEM_ID, FROM_USER_ID) values (?, ?, ?, ?, ?)
15:31:59,203 DEBUG BatcherImpl: preparing statement
15:31:59,203 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Comment#<null>]
15:31:59,203 DEBUG AbstractEntityPersister: Natively generated identity: 2
15:31:59,203 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,203 DEBUG BatcherImpl: closing statement
15:31:59,203 DEBUG JDBCTransaction: commit
15:31:59,203 DEBUG SessionImpl: flushing session
15:31:59,203 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Category
15:31:59,203 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Category.childCategories
15:31:59,234 DEBUG Cascades: cascading to saveOrUpdate()
15:31:59,234 DEBUG SessionImpl: saveOrUpdate() persistent instance
15:31:59,234 DEBUG Cascades: cascading to saveOrUpdate()
15:31:59,234 DEBUG SessionImpl: saveOrUpdate() persistent instance
15:31:59,234 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Category
15:31:59,234 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Category
15:31:59,234 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Category.childCategories
15:31:59,234 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Category
15:31:59,234 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Category
15:31:59,234 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Category.childCategories
15:31:59,234 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Category
15:31:59,234 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.User
15:31:59,234 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.User.billingDetails
15:31:59,234 DEBUG Cascades: cascading to saveOrUpdate()
15:31:59,234 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,234 DEBUG SessionImpl: saving [org.hibernate.auction.model.CreditCard#<null>]
15:31:59,234 DEBUG SessionImpl: executing insertions
15:31:59,234 DEBUG Versioning: using initial version: 0
15:31:59,234 DEBUG NormalizedEntityPersister: Inserting entity: org.hibernate.auction.model.CreditCard (native id)
15:31:59,234 DEBUG NormalizedEntityPersister: Version: 0
15:31:59,234 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,234 DEBUG SQL: insert into BILLING_DETAILS (VERSION, OWNER_NAME, CREATED, USER_ID) values (?, ?, ?, ?)
15:31:59,234 DEBUG BatcherImpl: preparing statement
15:31:59,296 DEBUG AbstractEntityPersister: Natively generated identity: 1
15:31:59,296 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,296 DEBUG BatcherImpl: closing statement
15:31:59,296 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,296 DEBUG SQL: insert into CREDIT_CARD (CC_TYPE, CC_NUMBER, EXP_MONTH, EXP_YEAR, CREDIT_CARD_ID) values (?, ?, ?, ?, ?)
15:31:59,296 DEBUG BatcherImpl: preparing statement
15:31:59,296 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,296 DEBUG BatcherImpl: closing statement
15:31:59,296 DEBUG Cascades: cascading to saveOrUpdate()
15:31:59,296 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,296 DEBUG SessionImpl: saving [org.hibernate.auction.model.BankAccount#<null>]
15:31:59,296 DEBUG SessionImpl: executing insertions
15:31:59,296 DEBUG Versioning: using initial version: 0
15:31:59,296 DEBUG NormalizedEntityPersister: Inserting entity: org.hibernate.auction.model.BankAccount (native id)
15:31:59,296 DEBUG NormalizedEntityPersister: Version: 0
15:31:59,296 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,296 DEBUG SQL: insert into BILLING_DETAILS (VERSION, OWNER_NAME, CREATED, USER_ID) values (?, ?, ?, ?)
15:31:59,296 DEBUG BatcherImpl: preparing statement
15:31:59,296 DEBUG AbstractEntityPersister: Natively generated identity: 2
15:31:59,296 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,296 DEBUG BatcherImpl: closing statement
15:31:59,296 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,296 DEBUG SQL: insert into BANK_ACCOUNT (ACCOUNT_NUMBER, BANK_NAME, BANK_SWIFT, BANK_ACCOUNT_ID) values (?, ?, ?, ?)
15:31:59,296 DEBUG BatcherImpl: preparing statement
15:31:59,312 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,312 DEBUG BatcherImpl: closing statement
15:31:59,312 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.User
15:31:59,312 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.User
15:31:59,312 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.User.billingDetails
15:31:59,312 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.User
15:31:59,312 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.User
15:31:59,312 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.User.billingDetails
15:31:59,312 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.User
15:31:59,312 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,312 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Item.bids
15:31:59,312 DEBUG Cascades: cascading to saveOrUpdate()
15:31:59,312 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,312 DEBUG SessionImpl: saving [org.hibernate.auction.model.Bid#<null>]
15:31:59,312 DEBUG SessionImpl: executing insertions
15:31:59,312 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Bid (native id)
15:31:59,312 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,312 DEBUG SQL: insert into BID (AMOUNT, AMOUNT_CURRENCY, CREATED, ITEM_ID, BIDDER_ID) values (?, ?, ?, ?, ?)
15:31:59,312 DEBUG BatcherImpl: preparing statement
15:31:59,312 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Bid#<null>]
15:31:59,328 DEBUG AbstractEntityPersister: Natively generated identity: 1
15:31:59,328 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,328 DEBUG BatcherImpl: closing statement
15:31:59,328 DEBUG Cascades: cascading to saveOrUpdate()
15:31:59,328 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,328 DEBUG SessionImpl: saving [org.hibernate.auction.model.Bid#<null>]
15:31:59,328 DEBUG SessionImpl: executing insertions
15:31:59,328 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Bid (native id)
15:31:59,328 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,328 DEBUG SQL: insert into BID (AMOUNT, AMOUNT_CURRENCY, CREATED, ITEM_ID, BIDDER_ID) values (?, ?, ?, ?, ?)
15:31:59,328 DEBUG BatcherImpl: preparing statement
15:31:59,328 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Bid#<null>]
15:31:59,328 DEBUG AbstractEntityPersister: Natively generated identity: 2
15:31:59,328 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,328 DEBUG BatcherImpl: closing statement
15:31:59,328 DEBUG Cascades: cascading to saveOrUpdate()
15:31:59,328 DEBUG SessionImpl: saveOrUpdate() unsaved instance
15:31:59,328 DEBUG SessionImpl: saving [org.hibernate.auction.model.Bid#<null>]
15:31:59,328 DEBUG SessionImpl: executing insertions
15:31:59,328 DEBUG EntityPersister: Inserting entity: org.hibernate.auction.model.Bid (native id)
15:31:59,328 DEBUG BatcherImpl: about to open: 0 open PreparedStatements, 0 open ResultSets
15:31:59,328 DEBUG SQL: insert into BID (AMOUNT, AMOUNT_CURRENCY, CREATED, ITEM_ID, BIDDER_ID) values (?, ?, ?, ?, ?)
15:31:59,328 DEBUG BatcherImpl: preparing statement
15:31:59,328 DEBUG EntityPersister: Dehydrating entity: [org.hibernate.auction.model.Bid#<null>]
15:31:59,328 DEBUG AbstractEntityPersister: Natively generated identity: 3
15:31:59,328 DEBUG BatcherImpl: done closing: 0 open PreparedStatements, 0 open ResultSets
15:31:59,328 DEBUG BatcherImpl: closing statement
15:31:59,328 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,328 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,328 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Item.bids
15:31:59,328 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,328 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,328 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Item.bids
15:31:59,328 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,328 DEBUG Cascades: processing cascades for: org.hibernate.auction.model.Item
15:31:59,328 DEBUG Cascades: cascading to collection: org.hibernate.auction.model.Item.bids
15:31:59,328 DEBUG Cascades: done processing cascades for: org.hibernate.auction.model.Item
15:31:59,328 DEBUG SessionImpl: Flushing entities and processing referenced collections
15:31:59,328 DEBUG SessionImpl: Collection found: [org.hibernate.auction.model.Category.childCategories#1], was: [<unreferenced>]
15:31:59,328 DEBUG SessionImpl: Collection found: [org.hibernate.auction.model.Category.items#1], was: [<unreferenced>]
15:31:59,328 DEBUG SessionImpl: Collection found: [org.hibernate.auction.model.Category.childCategories#2], was: [<unreferenced>]
15:31:59,343 DEBUG SessionImpl: Collection found: [org.hibernate.auction.model.Category.items#2], was: [<unreferenced>]
15:31:59,343 DEBUG SessionImpl: Collection found: [org.hibernate.auction.model.Category.childCategories#3], was: [<unreferenced>]
15:31:59,343 DEBUG SessionImpl: Collection found: [org.hibernate.auction.model.Category.items#3], was: [<unreferenced>]
15:31:59,343 DEBUG AbstractEntityPersister: org.hibernate.aucti