[Hibernate-issues] Insert to table done without error but no record is ever actually inserted

SourceForge Headquarters 1320 Columbia Street Suite 310 San Diego, CA 92101 +1 (858) 422-6466

I have a program to insert the record to a child table. The program
run without any error while no record is ever actually inserted.

******* xml mapping.
<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC "-//Hibernate/Hibernate Mapping DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">
<!-- Generated Mar 6, 2007 4:37:04 PM by Hibernate Tools 3.1.0.beta5 -->
<hibernate-mapping>
    <class name="db.hibernate.Step" table="STEP" schema="PUBLIC">
        <id name="stepid" type="int">
            <column name="STEPID" />
            <generator class="assigned" />
        </id>
        <many-to-one name="site" class="db.hibernate.Site" fetch="select">
            <column name="SITEID" not-null="true" />
        </many-to-one>
        <property name="name" type="string">
            <column name="NAME" length="1024" not-null="true" />
        </property>
        <property name="stepposition" type="int">
            <column name="STEPPOSITION" not-null="true" />
        </property>
        <property name="url" type="string">
            <column name="URL" length="1024" not-null="true" />
        </property>
        <property name="requestmethod" type="string">
            <column name="REQUESTMETHOD" length="6" not-null="true" />
        </property>
        <property name="formvalue" type="string">
            <column name="FORMVALUE" length="1024" />
        </property>
        <property name="expectedresponsecode" type="string">
            <column name="EXPECTEDRESPONSECODE" length="256" />
        </property>
        <property name="expectedmd5" type="string">
            <column name="EXPECTEDMD5" length="32" />
        </property>
        <property name="lastmodified" type="timestamp">
            <column name="LASTMODIFIED" length="6" />
        </property>
        <set name="monitorhistories" inverse="true" cascade="all">
            <key>
                <column name="STEPID" not-null="true" />
            </key>
            <one-to-many class="db.hibernate.Monitorhistory" />
        </set>
    </class>
</hibernate-mapping>

<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC "-//Hibernate/Hibernate Mapping DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">
<!-- Generated Mar 6, 2007 4:37:04 PM by Hibernate Tools 3.1.0.beta5 -->
<hibernate-mapping>
    <class name="db.hibernate.Site" table="SITE" schema="PUBLIC">
        <id name="siteid" type="int">
            <column name="SITEID" />
            <generator class="assigned" />
        </id>
        <property name="name" type="string">
            <column name="NAME" length="1024" not-null="true" unique="true" />
        </property>
        <property name="lastmodified" type="timestamp">
            <column name="LASTMODIFIED" length="6" />
        </property>
        <set name="steps" inverse="true">
            <key>
                <column name="SITEID" not-null="true" />
            </key>
            <one-to-many class="db.hibernate.Step" />
        </set>
    </class>
</hibernate-mapping>

<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC "-//Hibernate/Hibernate Mapping DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">
<!-- Generated Mar 6, 2007 4:37:04 PM by Hibernate Tools 3.1.0.beta5 -->
<hibernate-mapping>
    <class name="db.hibernate.Monitorhistory" table="MONITORHISTORY"
schema="PUBLIC">
        <id name="historyid" type="int">
            <column name="HISTORYID" />
            <generator class="native" />
        </id>
        <many-to-one name="step" class="db.hibernate.Step" fetch="select">
            <column name="STEPID" not-null="true" />
        </many-to-one>
        <property name="responsecode" type="string">
            <column name="RESPONSECODE" length="256" not-null="true" />
        </property>
        <property name="responsemessage" type="string">
            <column name="RESPONSEMESSAGE" length="1024" />
        </property>
        <property name="headers" type="string">
            <column name="HEADERS" length="3096" />
        </property>
        <property name="starttime" type="timestamp">
            <column name="STARTTIME" length="6" />
        </property>
        <property name="elapsetime" type="timestamp">
            <column name="ELAPSETIME" length="6" />
        </property>
    </class>
</hibernate-mapping>

******  java class

package db.hibernate;

// Generated Mar 6, 2007 4:37:03 PM by Hibernate Tools 3.1.0.beta5

import java.util.Date;

/**
 * Monitorhistory generated by hbm2java
 */
public class Monitorhistory implements java.io.Serializable {

	// Fields

	private int historyid;

	private Step step;

	private String responsecode;

	private String responsemessage;

	private String headers;

	private Date starttime;

	private Date elapsetime;

	// Constructors

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

	/** minimal constructor */
	public Monitorhistory(int historyid, Step step, String responsecode) {
		this.historyid = historyid;
		this.step = step;
		this.responsecode = responsecode;
	}

	/** full constructor */
	public Monitorhistory(int historyid, Step step, String responsecode,
			String responsemessage, String headers, Date starttime,
			Date elapsetime) {
		this.historyid = historyid;
		this.step = step;
		this.responsecode = responsecode;
		this.responsemessage = responsemessage;
		this.headers = headers;
		this.starttime = starttime;
		this.elapsetime = elapsetime;
	}

	// Property accessors
	public int getHistoryid() {
		return this.historyid;
	}

	public void setHistoryid(int historyid) {
		this.historyid = historyid;
	}

	public Step getStep() {
		return this.step;
	}

	public void setStep(Step step) {
		this.step = step;
	}

	public String getResponsecode() {
		return this.responsecode;
	}

	public void setResponsecode(String responsecode) {
		this.responsecode = responsecode;
	}

	public String getResponsemessage() {
		return this.responsemessage;
	}

	public void setResponsemessage(String responsemessage) {
		this.responsemessage = responsemessage;
	}

	public String getHeaders() {
		return this.headers;
	}

	public void setHeaders(String headers) {
		this.headers = headers;
	}

	public Date getStarttime() {
		return this.starttime;
	}

	public void setStarttime(Date starttime) {
		this.starttime = starttime;
	}

	public Date getElapsetime() {
		return this.elapsetime;
	}

	public void setElapsetime(Date elapsetime) {
		this.elapsetime = elapsetime;
	}

}

package db;

import java.util.Date;

import org.apache.log4j.Logger;
import org.hibernate.HibernateException;
import org.hibernate.Session;

import db.hibernate.Monitorhistory;
import db.hibernate.Step;
import db.util.HibernateUtil;

/**
 * @author frank
 *
 */
public class MonitorHistoryManager {

    private Logger logger;

    public MonitorHistoryManager(Logger _logger) {
    	logger = _logger;
    }

    public void store(Step _step, long _startTime, long _elapseTime,
int _responseCode, String _responseMessage, String _headers) {
    	Session session = HibernateUtil.getSessionFactory().getCurrentSession();
        try {
        	session.beginTransaction();

            Monitorhistory theHistory = new Monitorhistory();
            theHistory.setStep(_step);
            theHistory.setStarttime(new Date(_startTime));
            theHistory.setElapsetime(new Date(_elapseTime));
            theHistory.setResponsecode(new Integer(_responseCode).toString());
            theHistory.setResponsemessage(_responseMessage);
            theHistory.setHeaders(_headers);

            Step tempStep = (Step) session.load(Step.class, _step.getStepid());
            tempStep.getMonitorhistories().add(theHistory);
            session.save(theHistory);
            session.flush();
            session.getTransaction().commit();
            hsqlCleanup();
        } catch (HibernateException e) {
        	session.getSessionFactory().getCurrentSession().getTransaction().rollback();
            e.printStackTrace();
        }
    }

    private void hsqlCleanup() {
        try {
        	HibernateUtil.getSessionFactory().close();
        } catch (Exception e) {
            e.printStackTrace();
        }
    }
}

The debug log

2007-03-08 16:06:32,953 DEBUG
org.hibernate.impl.SessionImpl.<init>(SessionImpl.java:220) {} ==
opened session at timestamp: 11733411929
2007-03-08 16:06:32,953 DEBUG
org.hibernate.transaction.JDBCTransaction.begin(JDBCTransaction.java:54)
{} == begin
2007-03-08 16:06:32,953 DEBUG
org.hibernate.jdbc.ConnectionManager.openConnection(ConnectionManager.java:415)
{} == opening JDBC connection
2007-03-08 16:06:32,953 DEBUG
org.hibernate.connection.DriverManagerConnectionProvider.getConnection(DriverManagerConnectionProvider.java:109)
{} == opening new JDBC connection
2007-03-08 16:06:32,953 DEBUG
org.hibernate.connection.DriverManagerConnectionProvider.getConnection(DriverManagerConnectionProvider.java:115)
{} == created connection to: jdbc:hsqldb:file:c:\data\db\monitor2,
Isolation Level: 2
2007-03-08 16:06:32,953 DEBUG
org.hibernate.transaction.JDBCTransaction.begin(JDBCTransaction.java:59)
{} == current autocommit status: false
2007-03-08 16:06:32,953 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:40) {} ==
--- Start Loading Step record : 0
2007-03-08 16:06:32,953 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:42) {} ==
--- The Step record has been loaded
2007-03-08 16:06:32,953 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:43) {} ==
--- Start Attaching History to Step record
2007-03-08 16:06:32,953 DEBUG
org.hibernate.impl.SessionImpl.immediateLoad(SessionImpl.java:832) {}
== initializing proxy: [db.hibernate.Step#0]
2007-03-08 16:06:32,953 DEBUG
org.hibernate.loader.Loader.loadEntity(Loader.java:1843) {} == loading
entity: [db.hibernate.Step#0]
2007-03-08 16:06:32,953 DEBUG
org.hibernate.jdbc.AbstractBatcher.logOpenPreparedStatement(AbstractBatcher.java:358)
{} == about to open PreparedStatement (open PreparedStatements: 0,
globally: 0)
2007-03-08 16:06:32,968 DEBUG
org.hibernate.jdbc.AbstractBatcher.log(AbstractBatcher.java:393) {} ==
select step0_.STEPID as STEPID2_0_, step0_.SITEID as SITEID2_0_,
step0_.NAME as NAME2_0_, step0_.STEPPOSITION as STEPPOSI4_2_0_,
step0_.URL as URL2_0_, step0_.REQUESTMETHOD as REQUESTM6_2_0_,
step0_.FORMVALUE as FORMVALUE2_0_, step0_.EXPECTEDRESPONSECODE as
EXPECTED8_2_0_, step0_.EXPECTEDMD5 as EXPECTEDMD9_2_0_,
step0_.LASTMODIFIED as LASTMOD10_2_0_ from PUBLIC.STEP step0_ where
step0_.STEPID=?
2007-03-08 16:06:32,968 DEBUG
org.hibernate.jdbc.AbstractBatcher.logOpenResults(AbstractBatcher.java:374)
{} == about to open ResultSet (open ResultSets: 0, globally: 0)
2007-03-08 16:06:32,968 DEBUG
org.hibernate.loader.Loader.getRow(Loader.java:1164) {} == result row:
EntityKey[db.hibernate.Step#0]
2007-03-08 16:06:32,968 DEBUG
org.hibernate.jdbc.AbstractBatcher.logCloseResults(AbstractBatcher.java:381)
{} == about to close ResultSet (open ResultSets: 1, globally: 1)
2007-03-08 16:06:32,968 DEBUG
org.hibernate.jdbc.AbstractBatcher.logClosePreparedStatement(AbstractBatcher.java:366)
{} == about to close PreparedStatement (open PreparedStatements: 1,
globally: 1)
2007-03-08 16:06:32,968 DEBUG
org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:107)
{} == resolving associations for [db.hibernate.Step#0]
2007-03-08 16:06:32,968 DEBUG
org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:206)
{} == done materializing entity [db.hibernate.Step#0]
2007-03-08 16:06:32,968 DEBUG
org.hibernate.engine.StatefulPersistenceContext.initializeNonLazyCollections(StatefulPersistenceContext.java:748)
{} == initializing non-lazy collections
2007-03-08 16:06:32,968 DEBUG
org.hibernate.loader.Loader.loadEntity(Loader.java:1874) {} == done
entity load
2007-03-08 16:06:32,968 DEBUG
org.hibernate.loader.Loader.loadCollection(Loader.java:1977) {} ==
loading collection: [db.hibernate.Step.monitorhistories#0]
2007-03-08 16:06:32,968 DEBUG
org.hibernate.jdbc.AbstractBatcher.logOpenPreparedStatement(AbstractBatcher.java:358)
{} == about to open PreparedStatement (open PreparedStatements: 0,
globally: 0)
2007-03-08 16:06:32,968 DEBUG
org.hibernate.jdbc.AbstractBatcher.log(AbstractBatcher.java:393) {} ==
select monitorhis0_.STEPID as STEPID1_, monitorhis0_.HISTORYID as
HISTORYID1_, monitorhis0_.HISTORYID as HISTORYID1_0_,
monitorhis0_.STEPID as STEPID1_0_, monitorhis0_.RESPONSECODE as
RESPONSE3_1_0_, monitorhis0_.RESPONSEMESSAGE as RESPONSE4_1_0_,
monitorhis0_.HEADERS as HEADERS1_0_, monitorhis0_.STARTTIME as
STARTTIME1_0_, monitorhis0_.ELAPSETIME as ELAPSETIME1_0_ from
PUBLIC.MONITORHISTORY monitorhis0_ where monitorhis0_.STEPID=?
2007-03-08 16:06:32,968 DEBUG
org.hibernate.jdbc.AbstractBatcher.logOpenResults(AbstractBatcher.java:374)
{} == about to open ResultSet (open ResultSets: 0, globally: 0)
2007-03-08 16:06:32,968 DEBUG
org.hibernate.loader.Loader.handleEmptyCollections(Loader.java:1040)
{} == result set contains (possibly empty) collection:
[db.hibernate.Step.monitorhistories#0]
2007-03-08 16:06:32,968 DEBUG
org.hibernate.loader.Loader.getRow(Loader.java:1164) {} == result row:
EntityKey[db.hibernate.Monitorhistory#0]
2007-03-08 16:06:32,984 DEBUG
org.hibernate.loader.Loader.readCollectionElement(Loader.java:972) {}
== found row of collection: [db.hibernate.Step.monitorhistories#0]
2007-03-08 16:06:32,984 DEBUG
org.hibernate.jdbc.AbstractBatcher.logCloseResults(AbstractBatcher.java:381)
{} == about to close ResultSet (open ResultSets: 1, globally: 1)
2007-03-08 16:06:32,984 DEBUG
org.hibernate.jdbc.AbstractBatcher.logClosePreparedStatement(AbstractBatcher.java:366)
{} == about to close PreparedStatement (open PreparedStatements: 1,
globally: 1)
2007-03-08 16:06:32,984 DEBUG
org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:107)
{} == resolving associations for [db.hibernate.Monitorhistory#0]
2007-03-08 16:06:32,984 DEBUG
org.hibernate.engine.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:206)
{} == done materializing entity [db.hibernate.Monitorhistory#0]
2007-03-08 16:06:32,984 DEBUG
org.hibernate.engine.CollectionLoadContext.endLoadingCollections(CollectionLoadContext.java:262)
{} == 1 collections were found in result set for role:
db.hibernate.Step.monitorhistories
2007-03-08 16:06:32,984 DEBUG
org.hibernate.engine.CollectionLoadContext.endLoadingCollection(CollectionLoadContext.java:206)
{} == collection fully initialized:
[db.hibernate.Step.monitorhistories#0]
2007-03-08 16:06:32,984 DEBUG
org.hibernate.engine.CollectionLoadContext.endLoadingCollections(CollectionLoadContext.java:272)
{} == 1 collections initialized for role:
db.hibernate.Step.monitorhistories
2007-03-08 16:06:32,984 DEBUG
org.hibernate.engine.StatefulPersistenceContext.initializeNonLazyCollections(StatefulPersistenceContext.java:748)
{} == initializing non-lazy collections
2007-03-08 16:06:32,984 DEBUG
org.hibernate.loader.Loader.loadCollection(Loader.java:2001) {} ==
done loading collection
2007-03-08 16:06:32,984 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:45) {} ==
--- The History record has been Attached
2007-03-08 16:06:32,984 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:46) {} ==
--- Start Saving the History Record
2007-03-08 16:06:32,984 DEBUG
org.hibernate.jdbc.AbstractBatcher.logOpenPreparedStatement(AbstractBatcher.java:358)
{} == about to open PreparedStatement (open PreparedStatements: 0,
globally: 0)
2007-03-08 16:06:32,984 DEBUG
org.hibernate.jdbc.AbstractBatcher.log(AbstractBatcher.java:393) {} ==
select step_.STEPID, step_.SITEID as SITEID2_, step_.NAME as NAME2_,
step_.STEPPOSITION as STEPPOSI4_2_, step_.URL as URL2_,
step_.REQUESTMETHOD as REQUESTM6_2_, step_.FORMVALUE as FORMVALUE2_,
step_.EXPECTEDRESPONSECODE as EXPECTED8_2_, step_.EXPECTEDMD5 as
EXPECTEDMD9_2_, step_.LASTMODIFIED as LASTMOD10_2_ from PUBLIC.STEP
step_ where step_.STEPID=?
2007-03-08 16:06:32,984 DEBUG
org.hibernate.jdbc.AbstractBatcher.logClosePreparedStatement(AbstractBatcher.java:366)
{} == about to close PreparedStatement (open PreparedStatements: 1,
globally: 1)
2007-03-08 16:06:33,000 DEBUG
org.hibernate.event.def.AbstractSaveEventListener.performSaveOrReplicate(AbstractSaveEventListener.java:289)
{} == executing identity-insert immediately
2007-03-08 16:06:33,000 DEBUG
org.hibernate.jdbc.AbstractBatcher.logOpenPreparedStatement(AbstractBatcher.java:358)
{} == about to open PreparedStatement (open PreparedStatements: 0,
globally: 0)
2007-03-08 16:06:33,015 DEBUG
org.hibernate.jdbc.AbstractBatcher.log(AbstractBatcher.java:393) {} ==
insert into PUBLIC.MONITORHISTORY (HISTORYID, STEPID, RESPONSECODE,
RESPONSEMESSAGE, HEADERS, STARTTIME, ELAPSETIME) values (null, ?, ?,
?, ?, ?, ?)
2007-03-08 16:06:33,015 DEBUG
org.hibernate.jdbc.AbstractBatcher.logClosePreparedStatement(AbstractBatcher.java:366)
{} == about to close PreparedStatement (open PreparedStatements: 1,
globally: 1)
2007-03-08 16:06:33,015 DEBUG
org.hibernate.jdbc.AbstractBatcher.logOpenPreparedStatement(AbstractBatcher.java:358)
{} == about to open PreparedStatement (open PreparedStatements: 0,
globally: 0)
2007-03-08 16:06:33,015 DEBUG
org.hibernate.jdbc.AbstractBatcher.log(AbstractBatcher.java:393) {} ==
call identity()
2007-03-08 16:06:33,015 DEBUG
org.hibernate.id.IdentifierGeneratorFactory.getGeneratedIdentity(IdentifierGeneratorFactory.java:37)
{} == Natively generated identity: 1
2007-03-08 16:06:33,015 DEBUG
org.hibernate.jdbc.AbstractBatcher.logClosePreparedStatement(AbstractBatcher.java:366)
{} == about to close PreparedStatement (open PreparedStatements: 1,
globally: 1)
2007-03-08 16:06:33,015 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:48) {} ==
--- The History Record has been saved
2007-03-08 16:06:33,015 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:49) {} ==
--- Start flushing the History Record
2007-03-08 16:06:33,015 DEBUG
org.hibernate.event.def.AbstractFlushingEventListener.prepareEntityFlushes(AbstractFlushingEventListener.java:111)
{} == processing flush-time cascades
2007-03-08 16:06:33,015 DEBUG
org.hibernate.event.def.AbstractFlushingEventListener.prepareCollectionFlushes(AbstractFlushingEventListener.java:154)
{} == dirty checking collections
2007-03-08 16:06:33,015 DEBUG
org.hibernate.engine.CollectionEntry.preFlush(CollectionEntry.java:177)
{} == Collection dirty: [db.hibernate.Step.monitorhistories#0]
2007-03-08 16:06:33,015 DEBUG
org.hibernate.engine.Collections.processReachableCollection(Collections.java:176)
{} == Collection found: [db.hibernate.Step.monitorhistories#0], was:
[db.hibernate.Step.monitorhistories#0] (initialized)
2007-03-08 16:06:33,031 DEBUG
org.hibernate.event.def.AbstractFlushingEventListener.flushEverythingToExecutions(AbstractFlushingEventListener.java:85)
{} == Flushed: 0 insertions, 0 updates, 0 deletions to 3 objects
2007-03-08 16:06:33,031 DEBUG
org.hibernate.event.def.AbstractFlushingEventListener.flushEverythingToExecutions(AbstractFlushingEventListener.java:91)
{} == Flushed: 0 (re)creations, 1 updates, 0 removals to 1 collections
2007-03-08 16:06:33,031 DEBUG
org.hibernate.pretty.Printer.toString(Printer.java:83) {} == listing
entities:
2007-03-08 16:06:33,031 DEBUG
org.hibernate.pretty.Printer.toString(Printer.java:90) {} ==
db.hibernate.Step{stepposition=0, stepid=0, expectedmd5=12345,
expectedresponsecode=302, formvalue=null, site=db.hibernate.Site#0,
url=http://www..com/eng/, requestmethod=get,
monitorhistories=[db.hibernate.Monitorhistory#0,
db.hibernate.Monitorhistory#1], name=test , lastmodified=2007-03-01
14:35:26}
2007-03-08 16:06:33,031 DEBUG
org.hibernate.pretty.Printer.toString(Printer.java:90) {} ==
db.hibernate.Monitorhistory{responsemessage=OK, historyid=1,
headers=Date : Thu, 08 Mar 2007 08:06:19 GMT
Server : Apache/2.0.46 (Red Hat)
Last-Modified : Thu, 16 Feb 2006 07:20:48 GMT
ETag : "f640d8-35-40ce4447d9400"
Accept-Ranges : bytes
Content-Length : 53
Content-Type : text/html
, elapsetime=1970-01-01 08:00:00, responsecode=200,
starttime=2007-03-08 16:06:32, step=db.hibernate.Step#0}
2007-03-08 16:06:33,031 DEBUG
org.hibernate.pretty.Printer.toString(Printer.java:90) {} ==
db.hibernate.Monitorhistory{responsemessage=ok, historyid=0,
headers=header, elapsetime=1970-01-01 00:00:00, responsecode=200,
starttime=0970-01-01 00:00:00, step=db.hibernate.Step#0}
2007-03-08 16:06:33,031 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:51) {} ==
--- The History Record has been flushed
2007-03-08 16:06:33,031 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:52) {} ==
--- Start committing the History Record
2007-03-08 16:06:33,031 DEBUG
org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:103)
{} == commit
2007-03-08 16:06:33,031 DEBUG
org.hibernate.event.def.AbstractFlushingEventListener.prepareEntityFlushes(AbstractFlushingEventListener.java:111)
{} == processing flush-time cascades
2007-03-08 16:06:33,031 DEBUG
org.hibernate.event.def.AbstractFlushingEventListener.prepareCollectionFlushes(AbstractFlushingEventListener.java:154)
{} == dirty checking collections
2007-03-08 16:06:33,031 DEBUG
org.hibernate.engine.Collections.processReachableCollection(Collections.java:176)
{} == Collection found: [db.hibernate.Step.monitorhistories#0], was:
[db.hibernate.Step.monitorhistories#0] (initialized)
2007-03-08 16:06:33,031 DEBUG
org.hibernate.event.def.AbstractFlushingEventListener.flushEverythingToExecutions(AbstractFlushingEventListener.java:85)
{} == Flushed: 0 insertions, 0 updates, 0 deletions to 3 objects
2007-03-08 16:06:33,031 DEBUG
org.hibernate.event.def.AbstractFlushingEventListener.flushEverythingToExecutions(AbstractFlushingEventListener.java:91)
{} == Flushed: 0 (re)creations, 0 updates, 0 removals to 1 collections
2007-03-08 16:06:33,031 DEBUG
org.hibernate.pretty.Printer.toString(Printer.java:83) {} == listing
entities:
2007-03-08 16:06:33,031 DEBUG
org.hibernate.pretty.Printer.toString(Printer.java:90) {} ==
db.hibernate.Step{stepposition=0, stepid=0, expectedmd5=12345,
expectedresponsecode=302, formvalue=null, site=db.hibernate.Site#0,
url=http://www..com/eng/, requestmethod=get,
monitorhistories=[db.hibernate.Monitorhistory#0,
db.hibernate.Monitorhistory#1], name=test , lastmodified=2007-03-01
14:35:26}
2007-03-08 16:06:33,031 DEBUG
org.hibernate.pretty.Printer.toString(Printer.java:90) {} ==
db.hibernate.Monitorhistory{responsemessage=OK, historyid=1,
headers=Date : Thu, 08 Mar 2007 08:06:19 GMT
Server : Apache/2.0.46 (Red Hat)
Last-Modified : Thu, 16 Feb 2006 07:20:48 GMT
ETag : "f640d8-35-40ce4447d9400"
Accept-Ranges : bytes
Content-Length : 53
Content-Type : text/html
, elapsetime=1970-01-01 08:00:00, responsecode=200,
starttime=2007-03-08 16:06:32, step=db.hibernate.Step#0}
2007-03-08 16:06:33,031 DEBUG
org.hibernate.pretty.Printer.toString(Printer.java:90) {} ==
db.hibernate.Monitorhistory{responsemessage=ok, historyid=0,
headers=header, elapsetime=1970-01-01 00:00:00, responsecode=200,
starttime=0970-01-01 00:00:00, step=db.hibernate.Step#0}
2007-03-08 16:06:33,031 DEBUG
org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:116)
{} == committed JDBC Connection
2007-03-08 16:06:33,031 DEBUG
org.hibernate.jdbc.ConnectionManager.aggressiveRelease(ConnectionManager.java:398)
{} == aggressively releasing JDBC connection
2007-03-08 16:06:33,031 DEBUG
org.hibernate.jdbc.ConnectionManager.closeConnection(ConnectionManager.java:435)
{} == releasing JDBC connection [ (open PreparedStatements: 0,
globally: 0) (open ResultSets: 0, globally: 0)]
2007-03-08 16:06:33,031 DEBUG
db.MonitorHistoryManager.store(MonitorHistoryManager.java:54) {} ==
--- The History Record has been committed
2007-03-08 16:06:33,031 DEBUG
db.MonitorHistoryManager.hsqlCleanup(MonitorHistoryManager.java:64) {}
==    --- Start closing the Session Factory
2007-03-08 16:06:33,031 INFO
org.hibernate.impl.SessionFactoryImpl.close(SessionFactoryImpl.java:767)
{} == closing
2007-03-08 16:06:33,031 INFO
org.hibernate.connection.DriverManagerConnectionProvider.close(DriverManagerConnectionProvider.java:147)
{} == cleaning up connection pool:
jdbc:hsqldb:file:c:\data\db\monitor2
2007-03-08 16:06:33,031 DEBUG
db.MonitorHistoryManager.hsqlCleanup(MonitorHistoryManager.java:66) {}
==    --- The Session Factory has been closed
2007-03-08 16:06:33,031 DEBUG
util.MonitorManager.monitor(MonitorManager.java:111) {} == Done
2007-03-08 16:06:33,031 INFO
org.hibernate.impl.SessionFactoryImpl.close(SessionFactoryImpl.java:767)
{} == closing
2007-03-08 16:06:33,046 INFO
org.hibernate.connection.DriverManagerConnectionProvider.close(DriverManagerConnectionProvider.java:147)
{} == cleaning up connection pool:
jdbc:hsqldb:file:c:\data\db\monitor2

Anyone know why ?

[Hibernate-issues] Insert to table done without error but no record is ever actually inserted

An object relational-mapping (ORM) library for Java

[Hibernate-issues] Insert to table done without error but no record is ever actually inserted