Identity key generation fails on MySQL in Jetty

wliebenberg · **Joined:** Mon Jul 26, 2004 8:26 am **Posts:** 4

I am using hibernate 2.1, Jetty 4.2 on SuSE Linux. I get the error net.sf.hibernate.MappingException: Dialect does not support identity key generation, when I set the generator type in my mapping files to "identity". The error disappears when I use "assigned":

Below is an example of one of my mapping files.

<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD//EN"
"http://localhost/hibernate-mapping-2.0.dtd">

<hibernate-mapping>
<class name="com.tiscali.prepaid.PrepaidAccount" table="prepaid.PREPAID_ACCOUNTS">
<id name="id" column="ID" type="int" unsaved-value="0">
<generator class="identity"></generator>
</id>
<property name="subscriberUserId" column="SUBSCRIBER_USER_ID" type="string"/>
<property name="centsRemaining" column="CENTS_REMAINING" type="int"/>
<property name="usedPromotionalVoucher" column="USED_PROMOTIONAL" type="int" />
<property name="createDate" column="CREATE_DATE" type="timestamp"/>
<property name="usageWindowEndDate" column="USAGE_WINDOW_ENDDATE" type="timestamp"/>
<property name="status" column="STATUS" type="int"/>
<property name="billingStatus" column="BILLING_STATUS" type="int"/>
</class>
</hibernate-mapping>

I have tried this on both MySQL 3.8 (testing server) and MySQL 4.

This is the code that persists the object:

protected Boolean save(PrepaidAccount account) throws Exception
{
Session hibernateSession = null;
net.sf.hibernate.Transaction ta = null;
boolean error = false;
try
{
hibernateSession = DBManager.getInstance().openPrepaidSession();
ta = hibernateSession.beginTransaction();
hibernateSession.save(account);
ta.commit();
}
catch (Exception e)
{
error = true;
if (ta != null)
{
try
{
ta.rollback();
throw e;
}
catch (HibernateException he)
{
throw e;
}
}
}
finally
{
if (hibernateSession != null)
{
try
{
hibernateSession.close();
}
catch (HibernateException e)
{
throw e;
}
}
}

return new Boolean(true);
}

public Session openPrepaidSession() {
Session session = null;

try {
session = sessionFactory.openSession();
} catch (HibernateException e) {
log.error( "Failed to get Prepaid hibernate session." );
log.error( e );
}

return session;
}

This is my hibernate config file, and below it, the System.out output in Jetty.

<?xml version='1.0' encoding='utf-8'?>
<!DOCTYPE hibernate-configuration
PUBLIC "-//Hibernate/Hibernate Configuration DTD//EN"
"http://hibernate.sourceforge.net/hibernate-configuration-2.0.dtd">
<hibernate-configuration>
<session-factory name="prepaidDb">
<property name="connection.datasource">java:comp/env/prepaidDb</property>
<property name="Dialect">net.sf.hibernate.dialect.MySQLDialect</property>
<property name="hibernate.c3p0.max_size">2</property>
<property name="hibernate.c3p0.min_size">2</property>
<property name="hibernate.c3p0.timeout">5000</property>
<property name="hibernate.c3p0.max_statements">100</property>
<property name="hibernate.c3p0.validate">false</property>
<property name="show_sql">true</property>
<property name="use_outer_join">true</property>
<property name="jta.UserTransaction">java:comp/UserTransaction</property>

<mapping resource="com/tiscali/prepaid/PrepaidAccount.hbm.xml"/>
<mapping resource="com/tiscali/prepaid/PrepaidVoucher.hbm.xml"/>
<mapping resource="com/tiscali/prepaid/PrepaidActivatedVoucher.hbm.xml"/>
<mapping resource="com/tiscali/prepaid/IVRAccount.hbm.xml"/>
<mapping resource="com/tiscali/prepaid/RechargeTransaction.hbm.xml"/>
</session-factory>
</hibernate-configuration>

System.out:

alpha:/opt/Jetty # 15:02:53,318 INFO (DBManager.java:59) [DBManager] Looking up Prepaid datasource in JNDI tree for:java:comp/env/prepaidDb
15:02:53,325 INFO (DBManager.java:65) [DBManager] Completed Prepaid datasource lookup. Initializing hibernate.
15:02:53,360 INFO (Environment.java:432) [Environment] Hibernate 2.1.1
15:02:53,364 INFO (Environment.java:461) [Environment] hibernate.properties not found
15:02:53,368 INFO (Environment.java:481) [Environment] using CGLIB reflection optimizer
15:02:53,374 INFO (Configuration.java:843) [Configuration] configuring from resource: /hibernate.cfg.xml
15:02:53,375 INFO (Configuration.java:815) [Configuration] Configuration resource: /hibernate.cfg.xml
15:02:53,441 DEBUG (DTDEntityResolver.java:20) [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath under net/sf/hibernate/
15:02:53,442 DEBUG (DTDEntityResolver.java:29) [DTDEntityResolver] found http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath
15:02:53,476 DEBUG (Configuration.java:801) [Configuration] connection.datasource=java:comp/env/prepaidDb
15:02:53,477 DEBUG (Configuration.java:801) [Configuration] Dialect=net.sf.hibernate.dialect.MySQLDialect
15:02:53,478 DEBUG (Configuration.java:801) [Configuration] hibernate.c3p0.max_size=2
15:02:53,478 DEBUG (Configuration.java:801) [Configuration] hibernate.c3p0.min_size=2
15:02:53,479 DEBUG (Configuration.java:801) [Configuration] hibernate.c3p0.timeout=5000
15:02:53,480 DEBUG (Configuration.java:801) [Configuration] hibernate.c3p0.max_statements=100
15:02:53,482 DEBUG (Configuration.java:801) [Configuration] hibernate.c3p0.validate=false
15:02:53,483 DEBUG (Configuration.java:801) [Configuration] show_sql=true
15:02:53,485 DEBUG (Configuration.java:801) [Configuration] use_outer_join=true
15:02:53,488 DEBUG (Configuration.java:801) [Configuration] jta.UserTransaction=java:comp/UserTransaction
15:02:53,490 DEBUG (Configuration.java:952) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@1636e4e [Attribute: name resource value "com/tiscali/prepaid/PrepaidAccount.hbm.xml"]
15:02:53,490 INFO (Configuration.java:300) [Configuration] Mapping resource: com/tiscali/prepaid/PrepaidAccount.hbm.xml
15:02:53,685 INFO (Binder.java:225) [Binder] Mapping class: com.tiscali.prepaid.PrepaidAccount -> prepaid.PREPAID_ACCOUNTS
15:02:53,745 DEBUG (Binder.java:449) [Binder] Mapped property: id -> ID, type: integer
15:02:53,756 DEBUG (Binder.java:449) [Binder] Mapped property: subscriberUserId -> SUBSCRIBER_USER_ID, type: string
15:02:53,759 DEBUG (Binder.java:449) [Binder] Mapped property: centsRemaining -> CENTS_REMAINING, type: integer
15:02:53,760 DEBUG (Binder.java:449) [Binder] Mapped property: usedPromotionalVoucher -> USED_PROMOTIONAL, type: integer
15:02:53,761 DEBUG (Binder.java:449) [Binder] Mapped property: createDate -> CREATE_DATE, type: timestamp
15:02:53,761 DEBUG (Binder.java:449) [Binder] Mapped property: usageWindowEndDate -> USAGE_WINDOW_ENDDATE, type: timestamp
15:02:53,762 DEBUG (Binder.java:449) [Binder] Mapped property: status -> STATUS, type: integer
15:02:53,763 DEBUG (Binder.java:449) [Binder] Mapped property: billingStatus -> BILLING_STATUS, type: integer
15:02:53,764 DEBUG (Configuration.java:952) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@17918f0 [Attribute: name resource value "com/tiscali/prepaid/PrepaidVoucher.hbm.xml"]
15:02:53,764 INFO (Configuration.java:300) [Configuration] Mapping resource: com/tiscali/prepaid/PrepaidVoucher.hbm.xml
15:02:53,801 INFO (Binder.java:225) [Binder] Mapping class: com.tiscali.prepaid.PrepaidVoucher -> prepaid.PREPAID_VOUCHERS
15:02:53,802 DEBUG (Binder.java:449) [Binder] Mapped property: id -> ID, type: integer
15:02:53,803 DEBUG (Binder.java:449) [Binder] Mapped property: voucherPIN -> VOUCHER_PIN, type: string
15:02:53,805 DEBUG (Binder.java:449) [Binder] Mapped property: voucherSerialNumber -> VOUCHER_SERIAL_NO, type: string
15:02:53,806 DEBUG (Binder.java:449) [Binder] Mapped property: promotional -> PROMOTIONAL, type: integer
15:02:53,807 DEBUG (Binder.java:449) [Binder] Mapped property: createDate -> CREATE_DATE, type: timestamp
15:02:53,810 DEBUG (Binder.java:449) [Binder] Mapped property: expiryDate -> EXPIRY_DATE, type: timestamp
15:02:53,811 DEBUG (Binder.java:449) [Binder] Mapped property: centsAmount -> CENTS_AMOUNT, type: integer
15:02:53,813 DEBUG (Binder.java:449) [Binder] Mapped property: locked -> LOCKED, type: integer
15:02:53,814 DEBUG (Binder.java:449) [Binder] Mapped property: activated -> ACTIVATED, type: integer
15:02:53,816 DEBUG (Binder.java:449) [Binder] Mapped property: batchId -> BATCH_ID, type: integer
15:02:53,817 DEBUG (Binder.java:449) [Binder] Mapped property: vendorId -> VENDOR_ID, type: integer
15:02:53,818 DEBUG (Binder.java:449) [Binder] Mapped property: sentToVendorDate -> SENT_TO_VENDOR_DATE, type: timestamp
15:02:53,819 DEBUG (Configuration.java:952) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@1557c0 [Attribute: name resource value "com/tiscali/prepaid/PrepaidActivatedVoucher.hbm.xml"]
15:02:53,820 INFO (Configuration.java:300) [Configuration] Mapping resource: com/tiscali/prepaid/PrepaidActivatedVoucher.hbm.xml
15:02:53,841 INFO (Binder.java:225) [Binder] Mapping class: com.tiscali.prepaid.PrepaidActivatedVoucher -> prepaid.PREPAID_ACTIVATED_VOUCHERS
15:02:53,842 DEBUG (Binder.java:449) [Binder] Mapped property: id -> ID, type: integer
15:02:53,843 DEBUG (Binder.java:449) [Binder] Mapped property: voucherId -> VOUCHER_ID, type: integer
15:02:53,844 DEBUG (Binder.java:449) [Binder] Mapped property: prepaidAccountId -> PREPAID_ACCOUNT_ID, type: integer
15:02:53,845 DEBUG (Binder.java:449) [Binder] Mapped property: activationDate -> ACTIVATION_DATE, type: timestamp
15:02:53,845 DEBUG (Binder.java:449) [Binder] Mapped property: activationType -> ACTIVATION_TYPE, type: integer
15:02:53,846 DEBUG (Binder.java:449) [Binder] Mapped property: centsValue -> CENTS_VALUE, type: integer
15:02:53,849 DEBUG (Binder.java:449) [Binder] Mapped property: voucherPIN -> VOUCHER_PIN, type: string
15:02:53,850 DEBUG (Binder.java:449) [Binder] Mapped property: callCentreUsername -> CALL_CENTRE_USERNAME, type: string
15:02:53,851 DEBUG (Configuration.java:952) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@57ea52 [Attribute: name resource value "com/tiscali/prepaid/IVRAccount.hbm.xml"]
15:02:53,851 INFO (Configuration.java:300) [Configuration] Mapping resource: com/tiscali/prepaid/IVRAccount.hbm.xml
15:02:53,886 INFO (Binder.java:225) [Binder] Mapping class: com.tiscali.prepaid.IVRAccount -> prepaid.IVR_ACCOUNTS
15:02:53,889 DEBUG (Binder.java:449) [Binder] Mapped property: id -> ID, type: integer
15:02:53,889 DEBUG (Binder.java:449) [Binder] Mapped property: username -> USERNAME, type: string
15:02:53,890 DEBUG (Binder.java:449) [Binder] Mapped property: password -> PASSWORD, type: string
15:02:53,891 DEBUG (Binder.java:449) [Binder] Mapped property: status -> STATUS, type: integer
15:02:53,892 DEBUG (Configuration.java:952) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@1fc1a6 [Attribute: name resource value "com/tiscali/prepaid/RechargeTransaction.hbm.xml"]
15:02:53,892 INFO (Configuration.java:300) [Configuration] Mapping resource: com/tiscali/prepaid/RechargeTransaction.hbm.xml
15:02:53,919 INFO (Binder.java:225) [Binder] Mapping class: com.tiscali.prepaid.RechargeTransaction -> prepaid.RECHARGE_TRANSACTIONS
15:02:53,920 DEBUG (Binder.java:449) [Binder] Mapped property: id -> ID, type: integer
15:02:53,921 DEBUG (Binder.java:449) [Binder] Mapped property: transactionId -> TRANSACTION_ID, type: string
15:02:53,922 DEBUG (Binder.java:449) [Binder] Mapped property: accountId -> SUBSCRIBER_ID, type: string
15:02:53,924 DEBUG (Binder.java:449) [Binder] Mapped property: voucherPIN -> VOUCHER_PIN, type: string
15:02:53,924 DEBUG (Binder.java:449) [Binder] Mapped property: voucherValueCents -> CENTS_VALUE, type: integer
15:02:53,925 DEBUG (Binder.java:449) [Binder] Mapped property: usernameTryCount -> USERNAME_TRYCOUNT, type: integer
15:02:53,926 DEBUG (Binder.java:449) [Binder] Mapped property: voucherTryCount -> VOUCHER_TRYCOUNT, type: integer
15:02:53,927 DEBUG (Binder.java:449) [Binder] Mapped property: creationTimestamp -> CREATION_TIMESTAMP, type: timestamp
15:02:53,928 DEBUG (Binder.java:449) [Binder] Mapped property: lastActivityTimestamp -> LAST_ACTIVITY_TIMESTAMP, type: timestamp
15:02:53,928 DEBUG (Binder.java:449) [Binder] Mapped property: status -> STATUS_CODE, type: integer
15:02:53,931 DEBUG (Binder.java:449) [Binder] Mapped property: previousStatus -> PREVIOUS_STATUS_CODE, type: integer
15:02:53,931 DEBUG (Binder.java:449) [Binder] Mapped property: activationType -> ACTIVATION_TYPE, type: integer
15:02:53,932 DEBUG (Binder.java:449) [Binder] Mapped property: comments -> COMMENTS, type: string
15:02:53,933 INFO (Configuration.java:998) [Configuration] Configured SessionFactory: prepaidDb
15:02:53,934 DEBUG (Configuration.java:999) [Configuration] properties: {java.vendor=Sun Microsystems Inc., show_sql=true, connection.datasource=java:comp/env/prepaidDb, os.name=Linux, sun.boot.class.path=/usr/java/j2sdk1.4.2_04/jre/lib/rt.jar:/usr/java/j2sdk1.4.2_04/jre/lib/i18n.jar:/usr/java/j2sdk1.4.2_04/jre/lib/sunrsasign.jar:/usr/java/j2sdk1.4.2_04/jre/lib/jsse.jar:/usr/java/j2sdk1.4.2_04/jre/lib/jce.jar:/usr/java/j2sdk1.4.2_04/jre/lib/charsets.jar:/usr/java/j2sdk1.4.2_04/jre/classes, hibernate.c3p0.max_size=2, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.4.2_04-b05, hibernate.c3p0.min_size=2, user.name=root, iiop.java.naming.provider.url=iiop://localhost:19751, log4j.configuration=etc/log4j.xml, hibernate.session_factory_name=prepaidDb, hibernate.c3p0.timeout=5000, user.language=en, sun.boot.library.path=/usr/java/j2sdk1.4.2_04/jre/lib/i386, java.version=1.4.2_04, user.timezone=Africa/Johannesburg, sun.arch.data.model=32, hibernate.use_outer_join=true, hibernate.Dialect=net.sf.hibernate.dialect.MySQLDialect, java.endorsed.dirs=/usr/java/j2sdk1.4.2_04/jre/lib/endorsed, sun.cpu.isalist=, file.encoding.pkg=sun.io, org.objectweb.PortableInterceptor.JRMPInitializerClass.org.objectweb.jotm.jta.rmi.JTAInterceptorInitializer=, file.separator=/, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=48.0, user.country=US, java.home=/usr/java/j2sdk1.4.2_04/jre, java.vm.info=mixed mode, hibernate.c3p0.validate=false, os.version=2.4.21-138-smp, hibernate.connection.datasource=java:comp/env/prepaidDb, path.separator=:, java.vm.version=1.4.2_04-b05, java.util.prefs.PreferencesFactory=java.util.prefs.FileSystemPreferencesFactory, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, user.home=/root, java.specification.vendor=Sun Microsystems Inc., java.library.path=/usr/java/j2sdk1.4.2_04/jre/lib/i386/client:/usr/java/j2sdk1.4.2_04/jre/lib/i386:/usr/java/j2sdk1.4.2_04/jre/../lib/i386, java.vendor.url=http://java.sun.com/, jrmp.server.portnumber=0, java.vm.vendor=Sun Microsystems Inc., java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=/opt/Jetty:/opt/Jetty/etc:/opt/Jetty/lib/org.mortbay.jetty.jar:/opt/Jetty/lib/javax.servlet.jar:/opt/Jetty/lib/org.mortbay.jmx.jar:/opt/Jetty/ext/xercesImpl.jar:/opt/Jetty/ext/ant.jar:/opt/Jetty/ext/jasper-runtime.jar:/opt/Jetty/ext/jasper-compiler.jar:/opt/Jetty/ext/jsse.jar:/opt/Jetty/ext/jnet.jar:/opt/Jetty/ext/jmxri.jar:/opt/Jetty/ext/xml-apis.jar:/opt/Jetty/ext/jmxtools.jar:/opt/Jetty/ext/jcert.jar:/opt/Jetty/extra/lib/org.mortbay.ftp.jar:/opt/Jetty/extra/lib/org.mortbay.jaas.jar:/opt/Jetty/extra/lib/org.mortbay.jetty.plus.jar:/opt/Jetty/extra/lib/org.mortbay.j2ee.jar:/opt/Jetty/extra/lib/org.jboss.jetty.jar:/opt/Jetty/extra/lib/org.mortbay.jetty.plus.resource.jar:/opt/Jetty/extra/lib/org.mortbay.loadbalancer.jar:/opt/Jetty/extra/lib/org.mortbay.jmx-jdk1.2.jar:/opt/Jetty/extra/lib/org.mortbay.jetty-jdk1.2.jar:/opt/Jetty/extra/ext/jotm_iiop_stubs.jar:/opt/Jetty/extra/ext/activation.jar:/opt/Jetty/extra/ext/jonas_timer.jar:/opt/Jetty/extra/ext/classes12.zip:/opt/Jetty/extra/ext/p6spy.jar:/opt/Jetty/extra/ext/jts1_0.jar:/opt/Jetty/extra/ext/mysql-connector-java-3.0.7-stable-bin.jar:/opt/Jetty/extra/ext/ow_util_log_wrp_log4j.jar:/opt/Jetty/extra/ext/mail.jar:/opt/Jetty/extra/ext/objectweb-datasource.jar:/opt/Jetty/extra/ext/xapool.jar:/opt/Jetty/extra/ext/carol.jar:/opt/Jetty/extra/ext/commons-logging.jar:/opt/Jetty/extra/ext/log4j.jar:/opt/Jetty/extra/ext/jotm_jrmp_stubs.jar:/opt/Jetty/extra/ext/hsqldb.jar:/opt/Jetty/extra/ext/jotm.jar:/opt/Jetty/extra/ext/jta-spec1_0_1.jar:/opt/Jetty/extra/ext/ow_util_log_file.jar:/opt/Jetty/extra/ext/commons-cli.jar:/usr/java/j2sdk1.4.2_04/lib/tools.jar, org.omg.PortableInterceptor.ORBInitializerClass.org.objectweb.jotm.ots.OTSORBInitializer=, use_outer_join=true, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, javax.rmi.CORBA.PortableRemoteObjectClass=org.objectweb.carol.rmi.multi.JrmpPRODelegate, sun.cpu.endian=little, sun.os.patch.level=unknown, java.io.tmpdir=/tmp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, os.arch=i386, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/usr/java/j2sdk1.4.2_04/jre/lib/ext, user.dir=/opt/Jetty, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, file.encoding=ISO-8859-1, jta.UserTransaction=java:comp/UserTransaction, Dialect=net.sf.hibernate.dialect.MySQLDialect, hibernate.jta.UserTransaction=java:comp/UserTransaction, java.specification.version=1.4, jetty.home=/opt/Jetty, hibernate.show_sql=true, hibernate.c3p0.max_statements=100}
15:02:53,935 INFO (Configuration.java:584) [Configuration] processing one-to-many association mappings
15:02:53,936 INFO (Configuration.java:593) [Configuration] processing one-to-one association property references
15:02:53,938 INFO (Configuration.java:618) [Configuration] processing foreign key constraints
15:02:53,956 WARN (SettingsFactory.java:50) [SettingsFactory] No dialect set - using GenericDialect: The dialect was not set. Set the property hibernate.dialect.
15:02:53,959 INFO (Dialect.java:82) [Dialect] Using dialect: net.sf.hibernate.dialect.GenericDialect
15:02:53,960 INFO (SettingsFactory.java:62) [SettingsFactory] Use outer join fetching: true
15:02:53,967 INFO (NamingHelper.java:26) [NamingHelper] JNDI InitialContext properties:{}
15:02:53,970 INFO (DatasourceConnectionProvider.java:51) [DatasourceConnectionProvider] Using datasource: java:comp/env/prepaidDb
15:02:53,980 INFO (TransactionManagerLookupFactory.java:33) [TransactionManagerLookupFactory] No TransactionManagerLookup configured (in JTA environment, use of process level read-write cache is not recommended)
15:02:53,981 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection
15:02:53,981 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection must configure the pool...
15:02:53,982 DEBUG (Logger.java:23) [xapool] StandardXADataSource:getXAConnection(user, password)
15:02:53,997 DEBUG (Logger.java:23) [xapool] StandardDataSource:getConnection a new driver instance is created
15:02:54,149 DEBUG (Logger.java:23) [xapool] StandardDataSource:getConnection Connection from DriverManager is returned
15:02:54,154 DEBUG (Logger.java:23) [xapool] StandardXAStatefulConnection created
15:02:54,155 DEBUG (Logger.java:23) [xapool] StandardXAConnection created
15:02:54,156 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:create create a object for the pool
15:02:54,157 DEBUG (Logger.java:23) [xapool] StandardXADataSource:getXAConnection(user, password)
15:02:54,161 DEBUG (Logger.java:23) [xapool] StandardDataSource:getConnection Connection from DriverManager is returned
15:02:54,161 DEBUG (Logger.java:23) [xapool] StandardXAStatefulConnection created
15:02:54,164 DEBUG (Logger.java:23) [xapool] StandardXAConnection created
15:02:54,165 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:create create a object for the pool
15:02:54,167 DEBUG (Logger.java:23) [pool] GenericPool:start pool started
15:02:54,168 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection pool config :
GenericPool:Pool information :
num of element = 2
minsize = 2
maxsize = 15
lifeTime = 600000 (ms)
generation = 1
size of locked table = 0
size of unlocked table = 2
time to wait before deadlock = 300000 (ms)
number of wait loop = 10000

15:02:54,168 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection Try to give a connection (checkOut)
15:02:54,169 DEBUG (Logger.java:23) [pool] GenericPool:checkOut an object
15:02:54,171 DEBUG (Logger.java:23) [pool] GenericPool:checkOut return an object (after verification if needed)
15:02:54,172 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection checkOut returna new connection
15:02:54,174 DEBUG (Logger.java:23) [xapool] StandardXAConnection:getConnection
15:02:54,185 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache start
15:02:54,187 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache preparedStatementCache.size(lru)='0' preparedStatementCache.size(cache)='0' masterPrepStmtCache.size='1'
15:02:54,188 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache end
15:02:54,188 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:new StandardConnectionHandle with 16 prepared statement
15:02:54,190 DEBUG (Logger.java:23) [xapool] StandardXAConnection:getConnection return a connection
15:02:54,191 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection return a connection
15:02:54,192 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:close
15:02:54,193 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed
15:02:54,194 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed get a transaction
15:02:54,194 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed checkIn an object to the pool
15:02:54,195 DEBUG (Logger.java:23) [pool] GenericPool:checkIn return an object to the pool
15:02:54,196 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:close preparedStatementCache.size(lru)='0' preparedStatementCache.size(cache)='0' masterPrepStmtCache.size='1'
15:02:54,198 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close
15:02:54,199 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close globalTransaction='false' con.getAutoCommit='true' ttx='null'
15:02:54,199 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close do nothing else
15:02:54,200 INFO (SettingsFactory.java:89) [SettingsFactory] Use scrollable result sets: true
15:02:54,201 INFO (SettingsFactory.java:96) [SettingsFactory] echoing all SQL to stdout
15:02:54,201 INFO (SettingsFactory.java:99) [SettingsFactory] Query language substitutions: {}
15:02:54,202 INFO (SettingsFactory.java:110) [SettingsFactory] cache provider: net.sf.ehcache.hibernate.Provider
15:02:54,205 INFO (Configuration.java:1057) [Configuration] instantiating and configuring caches
15:02:54,318 INFO (SessionFactoryImpl.java:119) [SessionFactoryImpl] building session factory
15:02:54,319 DEBUG (SessionFactoryImpl.java:125) [SessionFactoryImpl] instantiating session factory with properties: {java.vendor=Sun Microsystems Inc., show_sql=true, connection.datasource=java:comp/env/prepaidDb, os.name=Linux, sun.boot.class.path=/usr/java/j2sdk1.4.2_04/jre/lib/rt.jar:/usr/java/j2sdk1.4.2_04/jre/lib/i18n.jar:/usr/java/j2sdk1.4.2_04/jre/lib/sunrsasign.jar:/usr/java/j2sdk1.4.2_04/jre/lib/jsse.jar:/usr/java/j2sdk1.4.2_04/jre/lib/jce.jar:/usr/java/j2sdk1.4.2_04/jre/lib/charsets.jar:/usr/java/j2sdk1.4.2_04/jre/classes, hibernate.c3p0.max_size=2, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.4.2_04-b05, hibernate.c3p0.min_size=2, user.name=root, iiop.java.naming.provider.url=iiop://localhost:19751, log4j.configuration=etc/log4j.xml, hibernate.session_factory_name=prepaidDb, hibernate.c3p0.timeout=5000, user.language=en, sun.boot.library.path=/usr/java/j2sdk1.4.2_04/jre/lib/i386, java.version=1.4.2_04, user.timezone=Africa/Johannesburg, sun.arch.data.model=32, hibernate.use_outer_join=true, hibernate.Dialect=net.sf.hibernate.dialect.MySQLDialect, java.endorsed.dirs=/usr/java/j2sdk1.4.2_04/jre/lib/endorsed, sun.cpu.isalist=, file.encoding.pkg=sun.io, org.objectweb.PortableInterceptor.JRMPInitializerClass.org.objectweb.jotm.jta.rmi.JTAInterceptorInitializer=, file.separator=/, java.specification.name=Java Platform API Specification, hibernate.cglib.use_reflection_optimizer=true, java.class.version=48.0, user.country=US, java.home=/usr/java/j2sdk1.4.2_04/jre, java.vm.info=mixed mode, hibernate.c3p0.validate=false, os.version=2.4.21-138-smp, hibernate.connection.datasource=java:comp/env/prepaidDb, path.separator=:, java.vm.version=1.4.2_04-b05, java.util.prefs.PreferencesFactory=java.util.prefs.FileSystemPreferencesFactory, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, user.home=/root, java.specification.vendor=Sun Microsystems Inc., java.library.path=/usr/java/j2sdk1.4.2_04/jre/lib/i386/client:/usr/java/j2sdk1.4.2_04/jre/lib/i386:/usr/java/j2sdk1.4.2_04/jre/../lib/i386, java.vendor.url=http://java.sun.com/, jrmp.server.portnumber=0, java.vm.vendor=Sun Microsystems Inc., java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=/opt/Jetty:/opt/Jetty/etc:/opt/Jetty/lib/org.mortbay.jetty.jar:/opt/Jetty/lib/javax.servlet.jar:/opt/Jetty/lib/org.mortbay.jmx.jar:/opt/Jetty/ext/xercesImpl.jar:/opt/Jetty/ext/ant.jar:/opt/Jetty/ext/jasper-runtime.jar:/opt/Jetty/ext/jasper-compiler.jar:/opt/Jetty/ext/jsse.jar:/opt/Jetty/ext/jnet.jar:/opt/Jetty/ext/jmxri.jar:/opt/Jetty/ext/xml-apis.jar:/opt/Jetty/ext/jmxtools.jar:/opt/Jetty/ext/jcert.jar:/opt/Jetty/extra/lib/org.mortbay.ftp.jar:/opt/Jetty/extra/lib/org.mortbay.jaas.jar:/opt/Jetty/extra/lib/org.mortbay.jetty.plus.jar:/opt/Jetty/extra/lib/org.mortbay.j2ee.jar:/opt/Jetty/extra/lib/org.jboss.jetty.jar:/opt/Jetty/extra/lib/org.mortbay.jetty.plus.resource.jar:/opt/Jetty/extra/lib/org.mortbay.loadbalancer.jar:/opt/Jetty/extra/lib/org.mortbay.jmx-jdk1.2.jar:/opt/Jetty/extra/lib/org.mortbay.jetty-jdk1.2.jar:/opt/Jetty/extra/ext/jotm_iiop_stubs.jar:/opt/Jetty/extra/ext/activation.jar:/opt/Jetty/extra/ext/jonas_timer.jar:/opt/Jetty/extra/ext/classes12.zip:/opt/Jetty/extra/ext/p6spy.jar:/opt/Jetty/extra/ext/jts1_0.jar:/opt/Jetty/extra/ext/mysql-connector-java-3.0.7-stable-bin.jar:/opt/Jetty/extra/ext/ow_util_log_wrp_log4j.jar:/opt/Jetty/extra/ext/mail.jar:/opt/Jetty/extra/ext/objectweb-datasource.jar:/opt/Jetty/extra/ext/xapool.jar:/opt/Jetty/extra/ext/carol.jar:/opt/Jetty/extra/ext/commons-logging.jar:/opt/Jetty/extra/ext/log4j.jar:/opt/Jetty/extra/ext/jotm_jrmp_stubs.jar:/opt/Jetty/extra/ext/hsqldb.jar:/opt/Jetty/extra/ext/jotm.jar:/opt/Jetty/extra/ext/jta-spec1_0_1.jar:/opt/Jetty/extra/ext/ow_util_log_file.jar:/opt/Jetty/extra/ext/commons-cli.jar:/usr/java/j2sdk1.4.2_04/lib/tools.jar, org.omg.PortableInterceptor.ORBInitializerClass.org.objectweb.jotm.ots.OTSORBInitializer=, use_outer_join=true, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, javax.rmi.CORBA.PortableRemoteObjectClass=org.objectweb.carol.rmi.multi.JrmpPRODelegate, sun.cpu.endian=little, sun.os.patch.level=unknown, java.io.tmpdir=/tmp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, os.arch=i386, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/usr/java/j2sdk1.4.2_04/jre/lib/ext, user.dir=/opt/Jetty, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, file.encoding=ISO-8859-1, jta.UserTransaction=java:comp/UserTransaction, Dialect=net.sf.hibernate.dialect.MySQLDialect, hibernate.jta.UserTransaction=java:comp/UserTransaction, java.specification.version=1.4, jetty.home=/opt/Jetty, hibernate.show_sql=true, hibernate.c3p0.max_statements=100}
15:02:54,612 ERROR (DBManager.java:74) [DBManager]
net.sf.hibernate.MappingException: Dialect does not support identity key generation
at net.sf.hibernate.dialect.Dialect.getIdentitySelectString(Dialect.java:272)
at net.sf.hibernate.persister.AbstractEntityPersister.<init>(AbstractEntityPersister.java:639)
at net.sf.hibernate.persister.EntityPersister.<init>(EntityPersister.java:699)
at net.sf.hibernate.persister.PersisterFactory.createClassPersister(PersisterFactory.java:41)
at net.sf.hibernate.impl.SessionFactoryImpl.<init>(SessionFactoryImpl.java:137)
at net.sf.hibernate.cfg.Configuration.buildSessionFactory(Configuration.java:739)
at com.tiscali.prepaid.util.DBManager.init(DBManager.java:67)
at com.tiscali.prepaid.util.DBManager.getInstance(DBManager.java:51)
at com.tiscali.prepaid.TAPPSServiceHandler.retrieve(TAPPSServiceHandler.java:444)
at com.tiscali.prepaid.TAPPSServiceHandler.create(TAPPSServiceHandler.java:50)
at org.apache.jsp.test_jsp._jspService(test_jsp.java:49)
at org.apache.jasper.runtime.HttpJspBase.service(HttpJspBase.java:137)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.apache.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:210)
at org.apache.jasper.servlet.JspServlet.serviceJspFile(JspServlet.java:295)
at org.apache.jasper.servlet.JspServlet.service(JspServlet.java:241)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:356)
at org.mortbay.jetty.servlet.WebApplicationHandler.dispatch(WebApplicationHandler.java:294)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:567)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1808)
at org.mortbay.jetty.servlet.WebApplicationContext.handle(WebApplicationContext.java:525)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1758)
at org.mortbay.jetty.plus.PlusWebAppContext.handle(PlusWebAppContext.java:127)
at org.mortbay.http.HttpServer.service(HttpServer.java:879)
at org.mortbay.http.HttpConnection.service(HttpConnection.java:790)
at org.mortbay.http.HttpConnection.handleNext(HttpConnection.java:952)
at org.mortbay.http.HttpConnection.handle(HttpConnection.java:807)
at org.mortbay.http.SocketListener.handleConnection(SocketListener.java:197)
at org.mortbay.util.ThreadedServer.handle(ThreadedServer.java:289)
at org.mortbay.util.ThreadPool$PoolThread.run(ThreadPool.java:496)
java.lang.NullPointerException
at com.tiscali.prepaid.TAPPSServiceHandler.retrieve(TAPPSServiceHandler.java:444)
at com.tiscali.prepaid.TAPPSServiceHandler.create(TAPPSServiceHandler.java:50)
at org.apache.jsp.test_jsp._jspService(test_jsp.java:49)
at org.apache.jasper.runtime.HttpJspBase.service(HttpJspBase.java:137)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.apache.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:210)
at org.apache.jasper.servlet.JspServlet.serviceJspFile(JspServlet.java:295)
at org.apache.jasper.servlet.JspServlet.service(JspServlet.java:241)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:356)
at org.mortbay.jetty.servlet.WebApplicationHandler.dispatch(WebApplicationHandler.java:294)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:567)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1808)
at org.mortbay.jetty.servlet.WebApplicationContext.handle(WebApplicationContext.java:525)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1758)
at org.mortbay.jetty.plus.PlusWebAppContext.handle(PlusWebAppContext.java:127)
at org.mortbay.http.HttpServer.service(HttpServer.java:879)
at org.mortbay.http.HttpConnection.service(HttpConnection.java:790)
at org.mortbay.http.HttpConnection.handleNext(HttpConnection.java:952)
at org.mortbay.http.HttpConnection.handle(HttpConnection.java:807)
at org.mortbay.http.SocketListener.handleConnection(SocketListener.java:197)
at org.mortbay.util.ThreadedServer.handle(ThreadedServer.java:289)
at org.mortbay.util.ThreadPool$PoolThread.run(ThreadPool.java:496)
15:12:54,184 DEBUG (Logger.java:23) [pool] GenericPool:cleanUp clean up the pool
15:12:54,184 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:expire expire a connection, remove from the pool
15:12:54,185 DEBUG (Logger.java:23) [xapool] StandardXAConnection:close the XAConnection
15:12:54,185 DEBUG (Logger.java:23) [xapool] StandardXADataSource:connectionClosed

christian · **Posted:** Mon Jul 26, 2004 9:46 am

So, the message says that MySQL doesn't support Identity columns (a special data type for PK generation). Use "native" or "sequence" and read the documentation about ID generators.

gavin · **Posted:** Mon Jul 26, 2004 9:50 am

Actually, MySQLDialect does support identity columns.

But you are not using MySQLDialect at runtime:

15:02:53,956 WARN (SettingsFactory.java:50) [SettingsFactory] No dialect set - using GenericDialect: The dialect was not set. Set the property hibernate.dialect.

Apparently you somehow hammer the dialect you have set earlier in the configuration.

Use your debugger.

wliebenberg · **Joined:** Mon Jul 26, 2004 8:26 am **Posts:** 4

Thanks for that! Actually I think it's because I was calling the property "Dialect" instead of "hibernate.dialect". I've changed it to "hibernate.dialect" and redeployed. Now the dialect is set, but I get a java.lang.UnsupportedOperationException exception the moment I save the object.

TAPPSServiceHandler is the class trying to do the saving.

16:25:22,140 INFO (DBManager.java:59) [DBManager] Looking up Prepaid datasource in JNDI tree for:java:comp/env/prepaidDb
16:25:22,149 INFO (DBManager.java:65) [DBManager] Completed Prepaid datasource lookup. Initializing hibernate.
16:25:22,183 INFO (Environment.java:462) [Environment] Hibernate 2.1.4
16:25:22,186 INFO (Environment.java:491) [Environment] hibernate.properties not found
16:25:22,191 INFO (Environment.java:522) [Environment] using CGLIB reflection optimizer
16:25:22,196 INFO (Configuration.java:872) [Configuration] configuring from resource: /hibernate.cfg.xml
16:25:22,197 INFO (Configuration.java:844) [Configuration] Configuration resource: /hibernate.cfg.xml
16:25:22,258 DEBUG (DTDEntityResolver.java:20) [DTDEntityResolver] trying to locate http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath under net/sf/hibernate/
16:25:22,260 DEBUG (DTDEntityResolver.java:29) [DTDEntityResolver] found http://hibernate.sourceforge.net/hibern ... on-2.0.dtd in classpath
16:25:22,294 DEBUG (Configuration.java:830) [Configuration] connection.datasource=java:comp/env/prepaidDb
16:25:22,295 DEBUG (Configuration.java:830) [Configuration] hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect
16:25:22,296 DEBUG (Configuration.java:830) [Configuration] hibernate.c3p0.max_size=2
16:25:22,296 DEBUG (Configuration.java:830) [Configuration] hibernate.c3p0.min_size=2
16:25:22,297 DEBUG (Configuration.java:830) [Configuration] hibernate.c3p0.timeout=5000
16:25:22,298 DEBUG (Configuration.java:830) [Configuration] hibernate.c3p0.max_statements=100
16:25:22,298 DEBUG (Configuration.java:830) [Configuration] hibernate.c3p0.validate=false
16:25:22,301 DEBUG (Configuration.java:830) [Configuration] show_sql=true
16:25:22,302 DEBUG (Configuration.java:830) [Configuration] use_outer_join=true
16:25:22,302 DEBUG (Configuration.java:830) [Configuration] jta.UserTransaction=java:comp/UserTransaction
16:25:22,304 DEBUG (Configuration.java:989) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@8b8a47 [Attribute: name resource value "com/tiscali/prepaid/PrepaidAccount.hbm.xml"]
16:25:22,305 INFO (Configuration.java:328) [Configuration] Mapping resource: com/tiscali/prepaid/PrepaidAccount.hbm.xml
16:25:22,491 INFO (Binder.java:229) [Binder] Mapping class: com.tiscali.prepaid.PrepaidAccount -> prepaid.PREPAID_ACCOUNTS
16:25:22,550 DEBUG (Binder.java:475) [Binder] Mapped property: id -> ID, type: integer
16:25:22,562 DEBUG (Binder.java:475) [Binder] Mapped property: subscriberUserId -> SUBSCRIBER_USER_ID, type: string
16:25:22,563 DEBUG (Binder.java:475) [Binder] Mapped property: centsRemaining -> CENTS_REMAINING, type: integer
16:25:22,564 DEBUG (Binder.java:475) [Binder] Mapped property: usedPromotionalVoucher -> USED_PROMOTIONAL, type: integer
16:25:22,565 DEBUG (Binder.java:475) [Binder] Mapped property: createDate -> CREATE_DATE, type: timestamp
16:25:22,566 DEBUG (Binder.java:475) [Binder] Mapped property: usageWindowEndDate -> USAGE_WINDOW_ENDDATE, type: timestamp
16:25:22,567 DEBUG (Binder.java:475) [Binder] Mapped property: status -> STATUS, type: integer
16:25:22,568 DEBUG (Binder.java:475) [Binder] Mapped property: billingStatus -> BILLING_STATUS, type: integer
16:25:22,570 DEBUG (Configuration.java:989) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@147917a [Attribute: name resource value "com/tiscali/prepaid/PrepaidVoucher.hbm.xml"]
16:25:22,571 INFO (Configuration.java:328) [Configuration] Mapping resource: com/tiscali/prepaid/PrepaidVoucher.hbm.xml
16:25:22,603 INFO (Binder.java:229) [Binder] Mapping class: com.tiscali.prepaid.PrepaidVoucher -> prepaid.PREPAID_VOUCHERS
16:25:22,604 DEBUG (Binder.java:475) [Binder] Mapped property: id -> ID, type: integer
16:25:22,607 DEBUG (Binder.java:475) [Binder] Mapped property: voucherPIN -> VOUCHER_PIN, type: string
16:25:22,608 DEBUG (Binder.java:475) [Binder] Mapped property: voucherSerialNumber -> VOUCHER_SERIAL_NO, type: string
16:25:22,609 DEBUG (Binder.java:475) [Binder] Mapped property: promotional -> PROMOTIONAL, type: integer
16:25:22,609 DEBUG (Binder.java:475) [Binder] Mapped property: createDate -> CREATE_DATE, type: timestamp
16:25:22,614 DEBUG (Binder.java:475) [Binder] Mapped property: expiryDate -> EXPIRY_DATE, type: timestamp
16:25:22,615 DEBUG (Binder.java:475) [Binder] Mapped property: centsAmount -> CENTS_AMOUNT, type: integer
16:25:22,616 DEBUG (Binder.java:475) [Binder] Mapped property: locked -> LOCKED, type: integer
16:25:22,618 DEBUG (Binder.java:475) [Binder] Mapped property: activated -> ACTIVATED, type: integer
16:25:22,620 DEBUG (Binder.java:475) [Binder] Mapped property: batchId -> BATCH_ID, type: integer
16:25:22,622 DEBUG (Binder.java:475) [Binder] Mapped property: vendorId -> VENDOR_ID, type: integer
16:25:22,623 DEBUG (Binder.java:475) [Binder] Mapped property: sentToVendorDate -> SENT_TO_VENDOR_DATE, type: timestamp
16:25:22,624 DEBUG (Configuration.java:989) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@32bd65 [Attribute: name resource value "com/tiscali/prepaid/PrepaidActivatedVoucher.hbm.xml"]
16:25:22,624 INFO (Configuration.java:328) [Configuration] Mapping resource: com/tiscali/prepaid/PrepaidActivatedVoucher.hbm.xml
16:25:22,645 INFO (Binder.java:229) [Binder] Mapping class: com.tiscali.prepaid.PrepaidActivatedVoucher -> prepaid.PREPAID_ACTIVATED_VOUCHERS
16:25:22,646 DEBUG (Binder.java:475) [Binder] Mapped property: id -> ID, type: integer
16:25:22,647 DEBUG (Binder.java:475) [Binder] Mapped property: voucherId -> VOUCHER_ID, type: integer
16:25:22,648 DEBUG (Binder.java:475) [Binder] Mapped property: prepaidAccountId -> PREPAID_ACCOUNT_ID, type: integer
16:25:22,649 DEBUG (Binder.java:475) [Binder] Mapped property: activationDate -> ACTIVATION_DATE, type: timestamp
16:25:22,649 DEBUG (Binder.java:475) [Binder] Mapped property: activationType -> ACTIVATION_TYPE, type: integer
16:25:22,651 DEBUG (Binder.java:475) [Binder] Mapped property: centsValue -> CENTS_VALUE, type: integer
16:25:22,652 DEBUG (Binder.java:475) [Binder] Mapped property: voucherPIN -> VOUCHER_PIN, type: string
16:25:22,653 DEBUG (Binder.java:475) [Binder] Mapped property: callCentreUsername -> CALL_CENTRE_USERNAME, type: string
16:25:22,654 DEBUG (Configuration.java:989) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@257f1b [Attribute: name resource value "com/tiscali/prepaid/IVRAccount.hbm.xml"]
16:25:22,654 INFO (Configuration.java:328) [Configuration] Mapping resource: com/tiscali/prepaid/IVRAccount.hbm.xml
16:25:22,691 INFO (Binder.java:229) [Binder] Mapping class: com.tiscali.prepaid.IVRAccount -> prepaid.IVR_ACCOUNTS
16:25:22,693 DEBUG (Binder.java:475) [Binder] Mapped property: id -> ID, type: integer
16:25:22,693 DEBUG (Binder.java:475) [Binder] Mapped property: username -> USERNAME, type: string
16:25:22,694 DEBUG (Binder.java:475) [Binder] Mapped property: password -> PASSWORD, type: string
16:25:22,695 DEBUG (Binder.java:475) [Binder] Mapped property: status -> STATUS, type: integer
16:25:22,696 DEBUG (Configuration.java:989) [Configuration] prepaidDb<-org.dom4j.tree.DefaultAttribute@b1a4e2 [Attribute: name resource value "com/tiscali/prepaid/RechargeTransaction.hbm.xml"]
16:25:22,698 INFO (Configuration.java:328) [Configuration] Mapping resource: com/tiscali/prepaid/RechargeTransaction.hbm.xml
16:25:22,722 INFO (Binder.java:229) [Binder] Mapping class: com.tiscali.prepaid.RechargeTransaction -> prepaid.RECHARGE_TRANSACTIONS
16:25:22,723 DEBUG (Binder.java:475) [Binder] Mapped property: id -> ID, type: integer
16:25:22,724 DEBUG (Binder.java:475) [Binder] Mapped property: transactionId -> TRANSACTION_ID, type: string
16:25:22,726 DEBUG (Binder.java:475) [Binder] Mapped property: accountId -> SUBSCRIBER_ID, type: string
16:25:22,727 DEBUG (Binder.java:475) [Binder] Mapped property: voucherPIN -> VOUCHER_PIN, type: string
16:25:22,728 DEBUG (Binder.java:475) [Binder] Mapped property: voucherValueCents -> CENTS_VALUE, type: integer
16:25:22,728 DEBUG (Binder.java:475) [Binder] Mapped property: usernameTryCount -> USERNAME_TRYCOUNT, type: integer
16:25:22,729 DEBUG (Binder.java:475) [Binder] Mapped property: voucherTryCount -> VOUCHER_TRYCOUNT, type: integer
16:25:22,730 DEBUG (Binder.java:475) [Binder] Mapped property: creationTimestamp -> CREATION_TIMESTAMP, type: timestamp
16:25:22,731 DEBUG (Binder.java:475) [Binder] Mapped property: lastActivityTimestamp -> LAST_ACTIVITY_TIMESTAMP, type: timestamp
16:25:22,733 DEBUG (Binder.java:475) [Binder] Mapped property: status -> STATUS_CODE, type: integer
16:25:22,733 DEBUG (Binder.java:475) [Binder] Mapped property: previousStatus -> PREVIOUS_STATUS_CODE, type: integer
16:25:22,734 DEBUG (Binder.java:475) [Binder] Mapped property: activationType -> ACTIVATION_TYPE, type: integer
16:25:22,735 DEBUG (Binder.java:475) [Binder] Mapped property: comments -> COMMENTS, type: string
16:25:22,736 INFO (Configuration.java:1030) [Configuration] Configured SessionFactory: prepaidDb
16:25:22,736 DEBUG (Configuration.java:1031) [Configuration] properties: {org.objectweb.PortableInterceptor.JRMPInitializerClass.org.objectweb.jotm.jta.rmi.JTAInterceptorInitializer=, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=/usr/java/j2sdk1.4.2_04/jre/lib/i386, java.vm.version=1.4.2_04-b05, connection.datasource=java:comp/env/prepaidDb, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=:, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, use_outer_join=true, user.country=US, sun.os.patch.level=unknown, java.vm.specification.name=Java Virtual Machine Specification, user.dir=/opt/Jetty, java.runtime.version=1.4.2_04-b05, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, hibernate.c3p0.validate=false, hibernate.c3p0.max_statements=100, java.endorsed.dirs=/usr/java/j2sdk1.4.2_04/jre/lib/endorsed, os.arch=i386, org.omg.PortableInterceptor.ORBInitializerClass.org.objectweb.jotm.ots.OTSORBInitializer=, java.io.tmpdir=/tmp, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., os.name=Linux, sun.java2d.fontpath=, jta.UserTransaction=java:comp/UserTransaction, log4j.configuration=etc/log4j.xml, hibernate.c3p0.timeout=5000, hibernate.connection.datasource=java:comp/env/prepaidDb, java.library.path=/usr/java/j2sdk1.4.2_04/jre/lib/i386/client:/usr/java/j2sdk1.4.2_04/jre/lib/i386:/usr/java/j2sdk1.4.2_04/jre/../lib/i386, java.specification.name=Java Platform API Specification, java.class.version=48.0, jetty.home=/opt/Jetty, java.util.prefs.PreferencesFactory=java.util.prefs.FileSystemPreferencesFactory, os.version=2.4.21-138-smp, user.home=/root, user.timezone=Africa/Johannesburg, javax.rmi.CORBA.PortableRemoteObjectClass=org.objectweb.carol.rmi.multi.JrmpPRODelegate, java.awt.printerjob=sun.print.PSPrinterJob, file.encoding=ISO-8859-1, java.specification.version=1.4, hibernate.c3p0.min_size=2, iiop.java.naming.provider.url=iiop://localhost:19751, show_sql=true, user.name=root, java.class.path=/opt/Jetty:/opt/Jetty/etc:/opt/Jetty/lib/org.mortbay.jetty.jar:/opt/Jetty/lib/javax.servlet.jar:/opt/Jetty/lib/org.mortbay.jmx.jar:/opt/Jetty/ext/xercesImpl.jar:/opt/Jetty/ext/ant.jar:/opt/Jetty/ext/jasper-runtime.jar:/opt/Jetty/ext/jasper-compiler.jar:/opt/Jetty/ext/jsse.jar:/opt/Jetty/ext/jnet.jar:/opt/Jetty/ext/jmxri.jar:/opt/Jetty/ext/xml-apis.jar:/opt/Jetty/ext/jmxtools.jar:/opt/Jetty/ext/jcert.jar:/opt/Jetty/extra/lib/org.mortbay.ftp.jar:/opt/Jetty/extra/lib/org.mortbay.jaas.jar:/opt/Jetty/extra/lib/org.mortbay.jetty.plus.jar:/opt/Jetty/extra/lib/org.mortbay.j2ee.jar:/opt/Jetty/extra/lib/org.jboss.jetty.jar:/opt/Jetty/extra/lib/org.mortbay.jetty.plus.resource.jar:/opt/Jetty/extra/lib/org.mortbay.loadbalancer.jar:/opt/Jetty/extra/lib/org.mortbay.jmx-jdk1.2.jar:/opt/Jetty/extra/lib/org.mortbay.jetty-jdk1.2.jar:/opt/Jetty/extra/ext/jotm_iiop_stubs.jar:/opt/Jetty/extra/ext/activation.jar:/opt/Jetty/extra/ext/jonas_timer.jar:/opt/Jetty/extra/ext/classes12.zip:/opt/Jetty/extra/ext/p6spy.jar:/opt/Jetty/extra/ext/jts1_0.jar:/opt/Jetty/extra/ext/mysql-connector-java-3.0.7-stable-bin.jar:/opt/Jetty/extra/ext/ow_util_log_wrp_log4j.jar:/opt/Jetty/extra/ext/mail.jar:/opt/Jetty/extra/ext/objectweb-datasource.jar:/opt/Jetty/extra/ext/xapool.jar:/opt/Jetty/extra/ext/carol.jar:/opt/Jetty/extra/ext/commons-logging.jar:/opt/Jetty/extra/ext/log4j.jar:/opt/Jetty/extra/ext/jotm_jrmp_stubs.jar:/opt/Jetty/extra/ext/hsqldb.jar:/opt/Jetty/extra/ext/jotm.jar:/opt/Jetty/extra/ext/jta-spec1_0_1.jar:/opt/Jetty/extra/ext/ow_util_log_file.jar:/opt/Jetty/extra/ext/commons-cli.jar:/usr/java/j2sdk1.4.2_04/lib/tools.jar, hibernate.show_sql=true, java.vm.specification.version=1.0, sun.arch.data.model=32, java.home=/usr/java/j2sdk1.4.2_04/jre, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, java.specification.vendor=Sun Microsystems Inc., user.language=en, hibernate.c3p0.max_size=2, hibernate.cglib.use_reflection_optimizer=true, java.vm.info=mixed mode, java.version=1.4.2_04, java.ext.dirs=/usr/java/j2sdk1.4.2_04/jre/lib/ext, sun.boot.class.path=/usr/java/j2sdk1.4.2_04/jre/lib/rt.jar:/usr/java/j2sdk1.4.2_04/jre/lib/i18n.jar:/usr/java/j2sdk1.4.2_04/jre/lib/sunrsasign.jar:/usr/java/j2sdk1.4.2_04/jre/lib/jsse.jar:/usr/java/j2sdk1.4.2_04/jre/lib/jce.jar:/usr/java/j2sdk1.4.2_04/jre/lib/charsets.jar:/usr/java/j2sdk1.4.2_04/jre/classes, java.vendor=Sun Microsystems Inc., hibernate.jta.UserTransaction=java:comp/UserTransaction, jrmp.server.portnumber=0, file.separator=/, hibernate.session_factory_name=prepaidDb, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, hibernate.use_outer_join=true, sun.cpu.isalist=}
16:25:22,737 INFO (Configuration.java:613) [Configuration] processing one-to-many association mappings
16:25:22,739 INFO (Configuration.java:622) [Configuration] processing one-to-one association property references
16:25:22,740 INFO (Configuration.java:647) [Configuration] processing foreign key constraints
16:25:22,764 INFO (Dialect.java:82) [Dialect] Using dialect: net.sf.hibernate.dialect.MySQLDialect
16:25:22,767 INFO (SettingsFactory.java:62) [SettingsFactory] Use outer join fetching: true
16:25:22,776 INFO (NamingHelper.java:26) [NamingHelper] JNDI InitialContext properties:{}
16:25:22,777 INFO (DatasourceConnectionProvider.java:51) [DatasourceConnectionProvider] Using datasource: java:comp/env/prepaidDb
16:25:22,787 INFO (TransactionManagerLookupFactory.java:33) [TransactionManagerLookupFactory] No TransactionManagerLookup configured (in JTA environment, use of process level read-write cache is not recommended)
16:25:22,787 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection
16:25:22,788 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection must configure the pool...
16:25:22,789 DEBUG (Logger.java:23) [xapool] StandardXADataSource:getXAConnection(user, password)
16:25:22,804 DEBUG (Logger.java:23) [xapool] StandardDataSource:getConnection a new driver instance is created
16:25:23,045 DEBUG (Logger.java:23) [xapool] StandardDataSource:getConnection Connection from DriverManager is returned
16:25:23,048 DEBUG (Logger.java:23) [xapool] StandardXAStatefulConnection created
16:25:23,049 DEBUG (Logger.java:23) [xapool] StandardXAConnection created
16:25:23,051 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:create create a object for the pool
16:25:23,052 DEBUG (Logger.java:23) [xapool] StandardXADataSource:getXAConnection(user, password)
16:25:23,057 DEBUG (Logger.java:23) [xapool] StandardDataSource:getConnection Connection from DriverManager is returned
16:25:23,058 DEBUG (Logger.java:23) [xapool] StandardXAStatefulConnection created
16:25:23,059 DEBUG (Logger.java:23) [xapool] StandardXAConnection created
16:25:23,059 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:create create a object for the pool
16:25:23,063 DEBUG (Logger.java:23) [pool] GenericPool:start pool started
16:25:23,064 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection pool config :
GenericPool:Pool information :
num of element = 2
minsize = 2
maxsize = 15
lifeTime = 600000 (ms)
generation = 1
size of locked table = 0
size of unlocked table = 2
time to wait before deadlock = 300000 (ms)
number of wait loop = 10000

16:25:23,064 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection Try to give a connection (checkOut)
16:25:23,065 DEBUG (Logger.java:23) [pool] GenericPool:checkOut an object
16:25:23,067 DEBUG (Logger.java:23) [pool] GenericPool:checkOut return an object (after verification if needed)
16:25:23,068 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection checkOut returna new connection
16:25:23,068 DEBUG (Logger.java:23) [xapool] StandardXAConnection:getConnection
16:25:23,081 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache start
16:25:23,083 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache preparedStatementCache.size(lru)='0' preparedStatementCache.size(cache)='0' masterPrepStmtCache.size='1'
16:25:23,084 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache end
16:25:23,085 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:new StandardConnectionHandle with 16 prepared statement
16:25:23,085 DEBUG (Logger.java:23) [xapool] StandardXAConnection:getConnection return a connection
16:25:23,086 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection return a connection
16:25:23,090 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:close
16:25:23,090 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed
16:25:23,091 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed get a transaction
16:25:23,092 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed checkIn an object to the pool
16:25:23,093 DEBUG (Logger.java:23) [pool] GenericPool:checkIn return an object to the pool
16:25:23,093 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:close preparedStatementCache.size(lru)='0' preparedStatementCache.size(cache)='0' masterPrepStmtCache.size='1'
16:25:23,094 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close
16:25:23,095 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close globalTransaction='false' con.getAutoCommit='true' ttx='null'
16:25:23,095 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close do nothing else
16:25:23,098 INFO (SettingsFactory.java:102) [SettingsFactory] Use scrollable result sets: true
16:25:23,098 INFO (SettingsFactory.java:105) [SettingsFactory] Use JDBC3 getGeneratedKeys(): true
16:25:23,099 INFO (SettingsFactory.java:108) [SettingsFactory] Optimize cache for minimal puts: false
16:25:23,099 INFO (SettingsFactory.java:114) [SettingsFactory] echoing all SQL to stdout
16:25:23,100 INFO (SettingsFactory.java:117) [SettingsFactory] Query language substitutions: {}
16:25:23,101 INFO (SettingsFactory.java:128) [SettingsFactory] cache provider: net.sf.ehcache.hibernate.Provider
16:25:23,104 INFO (Configuration.java:1093) [Configuration] instantiating and configuring caches
16:25:23,214 INFO (SessionFactoryImpl.java:119) [SessionFactoryImpl] building session factory
16:25:23,215 DEBUG (SessionFactoryImpl.java:125) [SessionFactoryImpl] instantiating session factory with properties: {org.objectweb.PortableInterceptor.JRMPInitializerClass.org.objectweb.jotm.jta.rmi.JTAInterceptorInitializer=, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=/usr/java/j2sdk1.4.2_04/jre/lib/i386, java.vm.version=1.4.2_04-b05, connection.datasource=java:comp/env/prepaidDb, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=:, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, use_outer_join=true, user.country=US, sun.os.patch.level=unknown, java.vm.specification.name=Java Virtual Machine Specification, user.dir=/opt/Jetty, java.runtime.version=1.4.2_04-b05, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, hibernate.c3p0.validate=false, hibernate.c3p0.max_statements=100, java.endorsed.dirs=/usr/java/j2sdk1.4.2_04/jre/lib/endorsed, os.arch=i386, org.omg.PortableInterceptor.ORBInitializerClass.org.objectweb.jotm.ots.OTSORBInitializer=, java.io.tmpdir=/tmp, line.separator=
, java.vm.specification.vendor=Sun Microsystems Inc., os.name=Linux, sun.java2d.fontpath=, jta.UserTransaction=java:comp/UserTransaction, log4j.configuration=etc/log4j.xml, hibernate.c3p0.timeout=5000, hibernate.connection.datasource=java:comp/env/prepaidDb, java.library.path=/usr/java/j2sdk1.4.2_04/jre/lib/i386/client:/usr/java/j2sdk1.4.2_04/jre/lib/i386:/usr/java/j2sdk1.4.2_04/jre/../lib/i386, java.specification.name=Java Platform API Specification, java.class.version=48.0, jetty.home=/opt/Jetty, java.util.prefs.PreferencesFactory=java.util.prefs.FileSystemPreferencesFactory, os.version=2.4.21-138-smp, user.home=/root, user.timezone=Africa/Johannesburg, javax.rmi.CORBA.PortableRemoteObjectClass=org.objectweb.carol.rmi.multi.JrmpPRODelegate, java.awt.printerjob=sun.print.PSPrinterJob, file.encoding=ISO-8859-1, java.specification.version=1.4, hibernate.c3p0.min_size=2, iiop.java.naming.provider.url=iiop://localhost:19751, show_sql=true, user.name=root, java.class.path=/opt/Jetty:/opt/Jetty/etc:/opt/Jetty/lib/org.mortbay.jetty.jar:/opt/Jetty/lib/javax.servlet.jar:/opt/Jetty/lib/org.mortbay.jmx.jar:/opt/Jetty/ext/xercesImpl.jar:/opt/Jetty/ext/ant.jar:/opt/Jetty/ext/jasper-runtime.jar:/opt/Jetty/ext/jasper-compiler.jar:/opt/Jetty/ext/jsse.jar:/opt/Jetty/ext/jnet.jar:/opt/Jetty/ext/jmxri.jar:/opt/Jetty/ext/xml-apis.jar:/opt/Jetty/ext/jmxtools.jar:/opt/Jetty/ext/jcert.jar:/opt/Jetty/extra/lib/org.mortbay.ftp.jar:/opt/Jetty/extra/lib/org.mortbay.jaas.jar:/opt/Jetty/extra/lib/org.mortbay.jetty.plus.jar:/opt/Jetty/extra/lib/org.mortbay.j2ee.jar:/opt/Jetty/extra/lib/org.jboss.jetty.jar:/opt/Jetty/extra/lib/org.mortbay.jetty.plus.resource.jar:/opt/Jetty/extra/lib/org.mortbay.loadbalancer.jar:/opt/Jetty/extra/lib/org.mortbay.jmx-jdk1.2.jar:/opt/Jetty/extra/lib/org.mortbay.jetty-jdk1.2.jar:/opt/Jetty/extra/ext/jotm_iiop_stubs.jar:/opt/Jetty/extra/ext/activation.jar:/opt/Jetty/extra/ext/jonas_timer.jar:/opt/Jetty/extra/ext/classes12.zip:/opt/Jetty/extra/ext/p6spy.jar:/opt/Jetty/extra/ext/jts1_0.jar:/opt/Jetty/extra/ext/mysql-connector-java-3.0.7-stable-bin.jar:/opt/Jetty/extra/ext/ow_util_log_wrp_log4j.jar:/opt/Jetty/extra/ext/mail.jar:/opt/Jetty/extra/ext/objectweb-datasource.jar:/opt/Jetty/extra/ext/xapool.jar:/opt/Jetty/extra/ext/carol.jar:/opt/Jetty/extra/ext/commons-logging.jar:/opt/Jetty/extra/ext/log4j.jar:/opt/Jetty/extra/ext/jotm_jrmp_stubs.jar:/opt/Jetty/extra/ext/hsqldb.jar:/opt/Jetty/extra/ext/jotm.jar:/opt/Jetty/extra/ext/jta-spec1_0_1.jar:/opt/Jetty/extra/ext/ow_util_log_file.jar:/opt/Jetty/extra/ext/commons-cli.jar:/usr/java/j2sdk1.4.2_04/lib/tools.jar, hibernate.show_sql=true, java.vm.specification.version=1.0, sun.arch.data.model=32, java.home=/usr/java/j2sdk1.4.2_04/jre, hibernate.dialect=net.sf.hibernate.dialect.MySQLDialect, java.specification.vendor=Sun Microsystems Inc., user.language=en, hibernate.c3p0.max_size=2, hibernate.cglib.use_reflection_optimizer=true, java.vm.info=mixed mode, java.version=1.4.2_04, java.ext.dirs=/usr/java/j2sdk1.4.2_04/jre/lib/ext, sun.boot.class.path=/usr/java/j2sdk1.4.2_04/jre/lib/rt.jar:/usr/java/j2sdk1.4.2_04/jre/lib/i18n.jar:/usr/java/j2sdk1.4.2_04/jre/lib/sunrsasign.jar:/usr/java/j2sdk1.4.2_04/jre/lib/jsse.jar:/usr/java/j2sdk1.4.2_04/jre/lib/jce.jar:/usr/java/j2sdk1.4.2_04/jre/lib/charsets.jar:/usr/java/j2sdk1.4.2_04/jre/classes, java.vendor=Sun Microsystems Inc., hibernate.jta.UserTransaction=java:comp/UserTransaction, jrmp.server.portnumber=0, file.separator=/, hibernate.session_factory_name=prepaidDb, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, sun.cpu.endian=little, sun.io.unicode.encoding=UnicodeLittle, hibernate.use_outer_join=true, sun.cpu.isalist=}
16:25:23,879 DEBUG (SessionFactoryObjectFactory.java:39) [SessionFactoryObjectFactory] initializing class SessionFactoryObjectFactory
16:25:23,883 DEBUG (SessionFactoryObjectFactory.java:76) [SessionFactoryObjectFactory] registered: 8a9b91a8fdfbd76400fdfbd767a40000 (prepaidDb)
16:25:23,884 INFO (SessionFactoryObjectFactory.java:86) [SessionFactoryObjectFactory] Factory name: prepaidDb
16:25:23,884 INFO (NamingHelper.java:26) [NamingHelper] JNDI InitialContext properties:{}
16:25:23,885 DEBUG (NamingHelper.java:48) [NamingHelper] binding: prepaidDb
16:25:23,888 DEBUG (NamingHelper.java:76) [NamingHelper] Bound name: prepaidDb
16:25:23,889 INFO (SessionFactoryObjectFactory.java:91) [SessionFactoryObjectFactory] Bound factory to JNDI name: prepaidDb
16:25:23,890 WARN (SessionFactoryObjectFactory.java:101) [SessionFactoryObjectFactory] InitialContext did not implement EventContext
16:25:23,891 DEBUG (SessionFactoryImpl.java:196) [SessionFactoryImpl] instantiated session factory
16:25:23,892 INFO (DBManager.java:69) [DBManager] Completed Prepaid hibernate initialization.
16:25:23,944 DEBUG (SessionImpl.java:555) [SessionImpl] opened session
16:25:23,947 DEBUG (SessionImpl.java:1526) [SessionImpl] find: from prepaidAccount in class com.tiscali.prepaid.PrepaidAccount where prepaidAccount.subscriberUserId = ?
16:25:23,949 DEBUG (QueryParameters.java:106) [QueryParameters] parameters: [werner]
16:25:23,964 DEBUG (QueryTranslator.java:147) [QueryTranslator] compiling query
16:25:23,989 DEBUG (SessionImpl.java:2242) [SessionImpl] flushing session
16:25:23,990 DEBUG (SessionImpl.java:2435) [SessionImpl] Flushing entities and processing referenced collections
16:25:23,991 DEBUG (SessionImpl.java:2776) [SessionImpl] Processing unreferenced collections
16:25:23,992 DEBUG (SessionImpl.java:2790) [SessionImpl] Scheduling collection removes/(re)creates/updates
16:25:23,992 DEBUG (SessionImpl.java:2266) [SessionImpl] Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
16:25:23,993 DEBUG (SessionImpl.java:2271) [SessionImpl] Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
16:25:23,994 DEBUG (SessionImpl.java:1814) [SessionImpl] Dont need to execute flush
16:25:23,994 DEBUG (QueryTranslator.java:199) [QueryTranslator] HQL: from prepaidAccount in class com.tiscali.prepaid.PrepaidAccount where prepaidAccount.subscriberUserId = ?
16:25:23,995 DEBUG (QueryTranslator.java:200) [QueryTranslator] SQL: select prepaidAccount.ID as ID, prepaidAccount.SUBSCRIBER_USER_ID as SUBSCRIB2_, prepaidAccount.CENTS_REMAINING as CENTS_RE3_, prepaidAccount.USED_PROMOTIONAL as USED_PRO4_, prepaidAccount.CREATE_DATE as CREATE_D5_, prepaidAccount.USAGE_WINDOW_ENDDATE as USAGE_WI6_, prepaidAccount.STATUS as STATUS, prepaidAccount.BILLING_STATUS as BILLING_8_ from prepaid.PREPAID_ACCOUNTS prepaidAccount where (prepaidAccount.SUBSCRIBER_USER_ID=? )
16:25:23,998 DEBUG (BatcherImpl.java:196) [BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
16:25:23,998 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection
16:25:23,999 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection Try to give a connection (checkOut)
16:25:24,000 DEBUG (Logger.java:23) [pool] GenericPool:checkOut an object
16:25:24,001 DEBUG (Logger.java:23) [pool] GenericPool:checkOut return an object (after verification if needed)
16:25:24,001 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection checkOut returna new connection
16:25:24,002 DEBUG (Logger.java:23) [xapool] StandardXAConnection:getConnection
16:25:24,005 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache start
16:25:24,006 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache end
16:25:24,007 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:new StandardConnectionHandle with 16 prepared statement
16:25:24,008 DEBUG (Logger.java:23) [xapool] StandardXAConnection:getConnection return a connection
16:25:24,008 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection return a connection
16:25:24,009 DEBUG (BatcherImpl.java:237) [SQL] select prepaidAccount.ID as ID, prepaidAccount.SUBSCRIBER_USER_ID as SUBSCRIB2_, prepaidAccount.CENTS_REMAINING as CENTS_RE3_, prepaidAccount.USED_PROMOTIONAL as USED_PRO4_, prepaidAccount.CREATE_DATE as CREATE_D5_, prepaidAccount.USAGE_WINDOW_ENDDATE as USAGE_WI6_, prepaidAccount.STATUS as STATUS, prepaidAccount.BILLING_STATUS as BILLING_8_ from prepaid.PREPAID_ACCOUNTS prepaidAccount where (prepaidAccount.SUBSCRIBER_USER_ID=? )
Hibernate: select prepaidAccount.ID as ID, prepaidAccount.SUBSCRIBER_USER_ID as SUBSCRIB2_, prepaidAccount.CENTS_REMAINING as CENTS_RE3_, prepaidAccount.USED_PROMOTIONAL as USED_PRO4_, prepaidAccount.CREATE_DATE as CREATE_D5_, prepaidAccount.USAGE_WINDOW_ENDDATE as USAGE_WI6_, prepaidAccount.STATUS as STATUS, prepaidAccount.BILLING_STATUS as BILLING_8_ from prepaid.PREPAID_ACCOUNTS prepaidAccount where (prepaidAccount.SUBSCRIBER_USER_ID=? )
16:25:24,010 DEBUG (BatcherImpl.java:241) [BatcherImpl] preparing statement
16:25:24,013 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:prepareStatement tx==null
16:25:24,021 DEBUG (Logger.java:23) [xapool] StandardXAPreparedStatement: Create an XAPreparedStatement with sql='select prepaidAccount.ID as ID, prepaidAccount.SUBSCRIBER_USER_ID as SUBSCRIB2_, prepaidAccount.CENTS_REMAINING as CENTS_RE3_, prepaidAccount.USED_PROMOTIONAL as USED_PRO4_, prepaidAccount.CREATE_DATE as CREATE_D5_, prepaidAccount.USAGE_WINDOW_ENDDATE as USAGE_WI6_, prepaidAccount.STATUS as STATUS, prepaidAccount.BILLING_STATUS as BILLING_8_ from prepaid.PREPAID_ACCOUNTS prepaidAccount where (prepaidAccount.SUBSCRIBER_USER_ID=? )'
16:25:24,022 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:checkPreparedCache object is *NOT* found
16:25:24,022 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:createPreparedStatement type ='0'
16:25:24,040 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:checkPreparedCache pstmt='com.mysql.jdbc.PreparedStatement@96b5c2: select prepaidAccount.ID as ID, prepaidAccount.SUBSCRIBER_USER_ID as SUBSCRIB2_, prepaidAccount.CENTS_REMAINING as CENTS_RE3_, prepaidAccount.USED_PROMOTIONAL as USED_PRO4_, prepaidAccount.CREATE_DATE as CREATE_D5_, prepaidAccount.USAGE_WINDOW_ENDDATE as USAGE_WI6_, prepaidAccount.STATUS as STATUS, prepaidAccount.BILLING_STATUS as BILLING_8_ from prepaid.PREPAID_ACCOUNTS prepaidAccount where (prepaidAccount.SUBSCRIBER_USER_ID=** NOT SPECIFIED ** )'
16:25:24,042 DEBUG (NullableType.java:46) [StringType] binding 'werner' to parameter: 1
16:25:24,046 DEBUG (Loader.java:197) [Loader] processing result set
16:25:24,047 DEBUG (Loader.java:226) [Loader] done processing result set (0 rows)
16:25:24,047 DEBUG (BatcherImpl.java:203) [BatcherImpl] done closing: 0 open PreparedStatements, 0 open ResultSets
16:25:24,048 DEBUG (BatcherImpl.java:261) [BatcherImpl] closing statement
16:25:24,049 DEBUG (Logger.java:23) [xapool] StandardXAPreparedStatement:close the XA prepared statement
16:25:24,052 DEBUG (Logger.java:23) [xapool] StandardXAPreparedStatement:close preparedStmtCacheSize='16'
16:25:24,053 DEBUG (Loader.java:239) [Loader] total objects hydrated: 0
16:25:24,053 DEBUG (SessionImpl.java:3112) [SessionImpl] initializing non-lazy collections
16:25:24,054 DEBUG (SessionImpl.java:573) [SessionImpl] closing session
16:25:24,055 DEBUG (SessionImpl.java:3332) [SessionImpl] disconnecting session
16:25:24,057 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:close
16:25:24,057 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed
16:25:24,058 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed get a transaction
16:25:24,059 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed checkIn an object to the pool
16:25:24,061 DEBUG (Logger.java:23) [pool] GenericPool:checkIn return an object to the pool
16:25:24,062 DEBUG (Logger.java:23) [xapool] PreparedStatementCache:cleanupObject class='class com.mysql.jdbc.PreparedStatement'
16:25:24,063 DEBUG (Logger.java:23) [xapool] PreparedStatementCache:cleanupObject close a PreparedStatement o='com.mysql.jdbc.PreparedStatement@96b5c2: select prepaidAccount.ID as ID, prepaidAccount.SUBSCRIBER_USER_ID as SUBSCRIB2_, prepaidAccount.CENTS_REMAINING as CENTS_RE3_, prepaidAccount.USED_PROMOTIONAL as USED_PRO4_, prepaidAccount.CREATE_DATE as CREATE_D5_, prepaidAccount.USAGE_WINDOW_ENDDATE as USAGE_WI6_, prepaidAccount.STATUS as STATUS, prepaidAccount.BILLING_STATUS as BILLING_8_ from prepaid.PREPAID_ACCOUNTS prepaidAccount where (prepaidAccount.SUBSCRIBER_USER_ID='werner' )'
16:25:24,064 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:close preparedStatementCache.size(lru)='0' preparedStatementCache.size(cache)='0' masterPrepStmtCache.size='1'
16:25:24,065 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close
16:25:24,065 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close globalTransaction='false' con.getAutoCommit='true' ttx='null'
16:25:24,066 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close do nothing else
16:25:24,066 DEBUG (SessionImpl.java:585) [SessionImpl] transaction completion
16:25:24,067 DEBUG (SessionImpl.java:555) [SessionImpl] opened session
16:25:24,071 DEBUG (JDBCTransaction.java:37) [JDBCTransaction] begin
16:25:24,072 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection
16:25:24,073 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection Try to give a connection (checkOut)
16:25:24,073 DEBUG (Logger.java:23) [pool] GenericPool:checkOut an object
16:25:24,074 DEBUG (Logger.java:23) [pool] GenericPool:checkOut return an object (after verification if needed)
16:25:24,074 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection checkOut returna new connection
16:25:24,075 DEBUG (Logger.java:23) [xapool] StandardXAConnection:getConnection
16:25:24,076 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache start
16:25:24,078 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:setupPreparedStatementCache end
16:25:24,079 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:new StandardConnectionHandle with 16 prepared statement
16:25:24,079 DEBUG (Logger.java:23) [xapool] StandardXAConnection:getConnection return a connection
16:25:24,080 DEBUG (Logger.java:23) [pool] StandardPoolDataSource:getConnection return a connection
16:25:24,081 DEBUG (JDBCTransaction.java:41) [JDBCTransaction] current autocommit status:true
16:25:24,082 DEBUG (JDBCTransaction.java:43) [JDBCTransaction] disabling autocommit
16:25:24,083 DEBUG (Logger.java:23) [xapool] CoreConnection:Setautocommit = false
16:25:24,086 DEBUG (SessionImpl.java:825) [SessionImpl] saving [com.tiscali.prepaid.PrepaidAccount#<null>]
16:25:24,088 DEBUG (SessionImpl.java:2305) [SessionImpl] executing insertions
16:25:24,091 DEBUG (EntityPersister.java:490) [EntityPersister] Inserting entity: com.tiscali.prepaid.PrepaidAccount (native id)
16:25:24,092 DEBUG (BatcherImpl.java:196) [BatcherImpl] about to open: 0 open PreparedStatements, 0 open ResultSets
16:25:24,093 DEBUG (BatcherImpl.java:237) [SQL] insert into prepaid.PREPAID_ACCOUNTS (SUBSCRIBER_USER_ID, CENTS_REMAINING, USED_PROMOTIONAL, CREATE_DATE, USAGE_WINDOW_ENDDATE, STATUS, BILLING_STATUS) values (?, ?, ?, ?, ?, ?, ?)
Hibernate: insert into prepaid.PREPAID_ACCOUNTS (SUBSCRIBER_USER_ID, CENTS_REMAINING, USED_PROMOTIONAL, CREATE_DATE, USAGE_WINDOW_ENDDATE, STATUS, BILLING_STATUS) values (?, ?, ?, ?, ?, ?, ?)
16:25:24,093 DEBUG (BatcherImpl.java:241) [BatcherImpl] preparing statement
16:25:24,096 DEBUG (JDBCTransaction.java:82) [JDBCTransaction] rollback
16:25:24,097 DEBUG (SessionImpl.java:585) [SessionImpl] transaction completion
16:25:24,098 DEBUG (JDBCTransaction.java:103) [JDBCTransaction] re-enabling autocommit
16:25:24,099 DEBUG (Logger.java:23) [xapool] CoreConnection:Setautocommit = true
16:25:24,101 DEBUG (SessionImpl.java:573) [SessionImpl] closing session
16:25:24,102 DEBUG (SessionImpl.java:3332) [SessionImpl] disconnecting session
16:25:24,102 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:close
16:25:24,103 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed
16:25:24,104 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed get a transaction
16:25:24,104 DEBUG (Logger.java:23) [pool] StandardXAPoolDataSource:connectionClosed checkIn an object to the pool
16:25:24,105 DEBUG (Logger.java:23) [pool] GenericPool:checkIn return an object to the pool
16:25:24,106 DEBUG (Logger.java:23) [xapool] StandardConnectionHandle:close preparedStatementCache.size(lru)='0' preparedStatementCache.size(cache)='0' masterPrepStmtCache.size='1'
16:25:24,108 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close
16:25:24,110 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close globalTransaction='false' con.getAutoCommit='true' ttx='null'
16:25:24,110 DEBUG (Logger.java:23) [xapool] StandardXAConnectionHandle:close do nothing else
16:25:24,111 DEBUG (SessionImpl.java:585) [SessionImpl] transaction completion
java.lang.UnsupportedOperationException
at org.enhydra.jdbc.core.CoreConnection.prepareStatement(CoreConnection.java:287)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:324)
at net.sf.hibernate.util.GetGeneratedKeysHelper.prepareStatement(GetGeneratedKeysHelper.java:39)
at net.sf.hibernate.impl.BatcherImpl.getPreparedStatement(BatcherImpl.java:246)
at net.sf.hibernate.impl.BatcherImpl.prepareStatement(BatcherImpl.java:61)
at net.sf.hibernate.persister.EntityPersister.insert(EntityPersister.java:525)
at net.sf.hibernate.persister.EntityPersister.insert(EntityPersister.java:432)
at net.sf.hibernate.impl.ScheduledIdentityInsertion.execute(ScheduledIdentityInsertion.java:29)
at net.sf.hibernate.impl.SessionImpl.doSave(SessionImpl.java:932)
at net.sf.hibernate.impl.SessionImpl.doSave(SessionImpl.java:857)
at net.sf.hibernate.impl.SessionImpl.saveWithGeneratedIdentifier(SessionImpl.java:775)
at net.sf.hibernate.impl.SessionImpl.save(SessionImpl.java:738)
at com.tiscali.prepaid.TAPPSServiceHandler.save(TAPPSServiceHandler.java:528)
at com.tiscali.prepaid.TAPPSServiceHandler.create(TAPPSServiceHandler.java:61)
at org.apache.jsp.test_jsp._jspService(test_jsp.java:49)
at org.apache.jasper.runtime.HttpJspBase.service(HttpJspBase.java:137)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.apache.jasper.servlet.JspServletWrapper.service(JspServletWrapper.java:210)
at org.apache.jasper.servlet.JspServlet.serviceJspFile(JspServlet.java:295)
at org.apache.jasper.servlet.JspServlet.service(JspServlet.java:241)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:853)
at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:356)
at org.mortbay.jetty.servlet.WebApplicationHandler.dispatch(WebApplicationHandler.java:294)
at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:567)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1808)
at org.mortbay.jetty.servlet.WebApplicationContext.handle(WebApplicationContext.java:525)
at org.mortbay.http.HttpContext.handle(HttpContext.java:1758)
at org.mortbay.jetty.plus.PlusWebAppContext.handle(PlusWebAppContext.java:127)
at org.mortbay.http.HttpServer.service(HttpServer.java:879)
at org.mortbay.http.HttpConnection.service(HttpConnection.java:790)
at org.mortbay.http.HttpConnection.handleNext(HttpConnection.java:952)
at org.mortbay.http.HttpConnection.handle(HttpConnection.java:807)
at org.mortbay.http.SocketListener.handleConnection(SocketListener.java:197)
at org.mortbay.util.ThreadedServer.handle(ThreadedServer.java:289)
at org.mortbay.util.ThreadPool$PoolThread.run(ThreadPool.java:496)

gavin · **Posted:** Mon Jul 26, 2004 10:27 am

disable use of getGeneratedKeys(). that is the default, by the way.

wliebenberg · **Joined:** Mon Jul 26, 2004 8:26 am **Posts:** 4

Sorry, pardon my ignorance, but how do I disable it? I assume that I have to add a config setting to the config file? Incidentally, do I need BOTH a cfg.xml file AND a .properties file, or will only the xml file do?

christian · **Posted:** Mon Jul 26, 2004 10:35 am

There is a whole chapter about this in the documentation, it includes a list of configuration options.

wliebenberg · **Joined:** Mon Jul 26, 2004 8:26 am **Posts:** 4

Dude, you're Spiderman! This problem has been bugging us for weeks. It's finally solved now. Thanks.