Changed hibernate log level to debug and turned on show_sql. Running same test, here is what I get.
I appears that the oracle id sequencer is running, but still no data getting saved.
Code:
14:48:36,765 INFO Environment:403 - Hibernate 2.0.3
14:48:36,765 INFO Environment:437 - loaded properties from resource hibernate.properties: {hibernate.cglib.use_reflection_optimizer=true, h
ibernate.dialect=net.sf.hibernate.dialect.OracleDialect, hibernate.show_sql=true, hibernate.connection.datasource=ruleDS}
14:48:36,781 INFO Environment:452 - using CGLIB reflection optimizer
14:48:36,781 INFO Environment:462 - JVM proxy support: true
14:48:36,781 INFO Configuration:283 - Mapping resource: org/scra/emall/rule/RuleDefinition.hbm.xml
14:48:36,937 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net
/sf/hibernate/
14:48:36,937 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
14:48:37,171 INFO Binder:178 - Mapping class: org.scra.emall.rule.RuleDefinition -> rule_def
14:48:37,359 DEBUG Binder:394 - Mapped property: id -> id, type: integer
14:48:37,390 DEBUG Binder:394 - Mapped property: name -> rule_name, type: string
14:48:37,406 DEBUG Binder:394 - Mapped property: action -> action, type: string
14:48:37,406 DEBUG Binder:394 - Mapped property: creationDate -> creation_date, type: timestamp
14:48:37,406 DEBUG Binder:394 - Mapped property: email -> email, type: string
14:48:37,406 DEBUG Binder:394 - Mapped property: errorMessage -> error_message, type: string
14:48:37,453 DEBUG Binder:394 - Mapped property: ruleConditions, type: java.util.Set
14:48:37,453 INFO Configuration:283 - Mapping resource: org/scra/emall/rule/ConditionDefinition.hbm.xml
14:48:37,468 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net
/sf/hibernate/
14:48:37,468 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
14:48:37,484 INFO Binder:178 - Mapping class: org.scra.emall.rule.ConditionDefinition -> rule_condition_def
14:48:37,484 DEBUG Binder:394 - Mapped property: id -> id, type: integer
14:48:37,484 DEBUG Binder:394 - Mapped property: operatorSymbol -> operatorSymbol, type: string
14:48:37,500 DEBUG Binder:394 - Mapped property: objectOperand, type: org.scra.emall.rule.ObjectOperand
14:48:37,500 DEBUG Binder:394 - Mapped property: valueOperand, type: org.scra.emall.rule.ValueOperand
14:48:37,500 INFO Configuration:283 - Mapping resource: org/scra/emall/rule/ObjectOperand.hbm.xml
14:48:37,500 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net
/sf/hibernate/
14:48:37,515 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
14:48:37,531 INFO Binder:178 - Mapping class: org.scra.emall.rule.ObjectOperand -> rule_condition_object_operand
14:48:37,531 DEBUG Binder:394 - Mapped property: id -> id, type: integer
14:48:37,531 DEBUG Binder:394 - Mapped property: objectClass -> object_class, type: string
14:48:37,531 DEBUG Binder:394 - Mapped property: identifier -> identifier, type: string
14:48:37,531 DEBUG Binder:394 - Mapped property: objectProperty -> object_property, type: string
14:48:37,531 INFO Configuration:283 - Mapping resource: org/scra/emall/rule/ValueOperand.hbm.xml
14:48:37,546 DEBUG DTDEntityResolver:20 - trying to locate http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath under net
/sf/hibernate/
14:48:37,546 DEBUG DTDEntityResolver:29 - found http://hibernate.sourceforge.net/hibernate-mapping-2.0.dtd in classpath
14:48:37,562 INFO Binder:178 - Mapping class: org.scra.emall.rule.ValueOperand -> rule_condition_value_operand
14:48:37,578 DEBUG Binder:394 - Mapped property: id -> id, type: integer
14:48:37,578 DEBUG Binder:394 - Mapped property: objectValue -> objectValue, type: string
14:48:37,578 INFO Configuration:492 - processing one-to-many association mappings
14:48:37,578 DEBUG Binder:1134 - Second pass for collection: org.scra.emall.rule.RuleDefinition.ruleConditions
14:48:37,578 INFO Binder:1025 - Mapping collection: org.scra.emall.rule.RuleDefinition.ruleConditions -> rule_condition_def
14:48:37,578 DEBUG Binder:1146 - Mapped collection key: rule_id, one-to-many: org.scra.emall.rule.ConditionDefinition
14:48:37,578 INFO Configuration:503 - processing foreign key constraints
14:48:37,593 DEBUG Configuration:513 - resolving reference to class: org.scra.emall.rule.RuleDefinition
14:48:37,828 INFO SessionFactoryImpl:132 - building session factory
14:48:37,828 DEBUG SessionFactoryImpl:134 - instantiating session factory with properties: {java.vendor=Sun Microsystems Inc., org.xml.sax.p
arser=weblogic.xml.jaxp.RegistryParser, os.name=Windows XP, sun.boot.class.path=D:\bea\JDK141~1\jre\lib\rt.jar;D:\bea\JDK141~1\jre\lib\i18n.
jar;D:\bea\JDK141~1\jre\lib\sunrsasign.jar;D:\bea\JDK141~1\jre\lib\jsse.jar;D:\bea\JDK141~1\jre\lib\jce.jar;D:\bea\JDK141~1\jre\lib\charsets
.jar;D:\bea\JDK141~1\jre\classes, sun.java2d.fontpath=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.4.1_03-b0
2, weblogic.Name=myserver, jmx.implementation.vendor=Sun Microsystems, user.name=doug, jmx.implementation.name=JMX RI, user.language=en, jav
a.naming.factory.initial=weblogic.jndi.WLInitialContextFactory, sun.boot.library.path=D:\bea\JDK141~1\jre\bin, jmx.specification.name=Java M
anagement Extensions, java.version=1.4.1_03, user.timezone=America/New_York, sun.arch.data.model=32, javax.rmi.CORBA.UtilClass=weblogic.iiop
.UtilDelegateImpl, jmx.specification.version=1.0 Final Release, java.endorsed.dirs=D:\bea\JDK141~1\jre\lib\endorsed, vde.home=.\myserver\lda
p, sun.cpu.isalist=pentium i486 i386, jmx.implementation.version=1.0, file.encoding.pkg=sun.io, weblogic.mbeanLegalClause.ByPass=false, 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=D:\bea\JDK141~1\jre, java.vm.info=mixed mode, os.version=5.1, log4j.config=log4j.properties, hibernate.connect
ion.datasource=ruleDS, org.omg.CORBA.ORBSingletonClass=weblogic.corba.orb.ORB, path.separator=;, java.vm.version=1.4.1_03-b02, java.util.pre
fs.PreferencesFactory=java.util.prefs.WindowsPreferencesFactory, user.variant=, java.protocol.handler.pkgs=weblogic.utils|weblogic.utils|web
logic.net, jmx.specification.vendor=Sun Microsystems, java.awt.printerjob=sun.awt.windows.WPrinterJob, java.security.policy=D:\bea\WEBLOG~1\
server\lib\weblogic.policy, sun.io.unicode.encoding=UnicodeLittle, awt.toolkit=sun.awt.windows.WToolkit, java.naming.factory.url.pkgs=weblog
ic.jndi.factories:weblogic.corba.j2ee.naming.url, user.home=C:\Documents and Settings\doug, java.specification.vendor=Sun Microsystems Inc.,
org.xml.sax.driver=weblogic.apache.xerces.parsers.SAXParser, java.library.path=D:\bea\JDK141~1\bin;.;C:\WINDOWS\System32;C:\WINDOWS;D:\bea\
WEBLOG~1\server\bin;D:\bea\JDK141~1\jre\bin;D:\bea\JDK141~1\bin;C:\oracle\ora92\bin;C:\Program Files\Oracle\jre\1.1.8\bin;C:\WINDOWS\system3
2;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\Program Files\Common Files\Adaptec Shared\System;c:\bin\vslick\win;D:\bea\jdk141_03\bin;C:\Program
Files\SSH Communications Security\SSH Secure Shell;C:\Program Files\GNU\WinCvs 1.3\CVSNT;C:\cygwin;C:\bin\maven-1.0-beta-10\bin;C:\bin;D:\be
a\WEBLOG~1\server\bin\oci920_8, java.vendor.url=http://java.sun.com/, java.vm.vendor=Sun Microsystems Inc., hibernate.dialect=net.sf.hiberna
te.dialect.OracleDialect, java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, java.class.path=D:\bea\JDK141~1\lib\tools.jar;
D:\bea\WEBLOG~1\server\lib\weblogic_sp.jar;D:\bea\WEBLOG~1\server\lib\weblogic.jar;D:\bea\WEBLOG~1\server\lib\ojdbc14.jar;D:\bea\WEBLOG~1\co
mmon\eval\pointbase\lib\pbserver44.jar;D:\bea\WEBLOG~1\common\eval\pointbase\lib\pbclient44.jar;D:\bea\JDK141~1\jre\lib\rt.jar;D:\bea\WEBLOG
~1\server\lib\webservices.jar;, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, javax.rmi.
CORBA.PortableRemoteObjectClass=weblogic.iiop.PortableRemoteObjectDelegateImpl, sun.cpu.endian=little, sun.os.patch.level=Service Pack 1, ja
va.io.tmpdir=C:\DOCUME~1\doug\LOCALS~1\Temp\, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, os.arch=x86, java.awt.graphicse
nv=sun.awt.Win32GraphicsEnvironment, java.ext.dirs=D:\bea\JDK141~1\jre\lib\ext, user.dir=D:\bea\user_projects\domains\ruleEngine, line.separ
ator=
, java.vm.name=Java HotSpot(TM) Client VM, javax.xml.soap.MessageFactory=weblogic.webservice.core.soap.MessageFactoryImpl, file.encoding=Cp1
252, org.omg.CORBA.ORBClass=weblogic.corba.orb.ORB, javax.xml.rpc.ServiceFactory=weblogic.webservice.core.rpc.ServiceFactoryImpl, weblogic.P
roductionModeEnabled=false, java.specification.version=1.4, hibernate.show_sql=true}
14:48:37,890 INFO Dialect:83 - Using dialect: net.sf.hibernate.dialect.OracleDialect
14:48:37,906 INFO NamingHelper:26 - JNDI InitialContext properties:{}
14:48:37,906 INFO DatasourceConnectionProvider:52 - Using datasource: ruleDS
14:48:37,906 INFO SessionFactoryImpl:162 - Use outer join fetching: true
14:48:38,234 INFO SessionFactoryImpl:185 - Use scrollable result sets: true
14:48:38,234 INFO SessionFactoryImpl:186 - JDBC 2 max batch size: 15
14:48:38,250 INFO SessionFactoryImpl:194 - echoing all SQL to stdout
14:48:39,140 DEBUG SessionFactoryObjectFactory:39 - initializing class SessionFactoryObjectFactory
14:48:39,156 DEBUG SessionFactoryObjectFactory:76 - registered: 13f8b386f7df3b2600f7df3b2be40000 (unnamed)
14:48:39,156 INFO SessionFactoryObjectFactory:82 - no JDNI name configured
14:48:39,156 INFO SessionFactoryImpl:269 - Query language substitutions: {}
14:48:39,171 DEBUG SessionFactoryImpl:281 - instantiated session factory
14:48:39,187 DEBUG SessionFactoryImpl:595 - serializing: 13f8b386f7df3b2600f7df3b2be40000
14:48:39,187 DEBUG SessionFactoryImpl:597 - serialized
14:48:39,187 DEBUG SessionFactoryImpl:590 - deserializing
14:48:39,187 DEBUG SessionFactoryImpl:592 - deserialized: 13f8b386f7df3b2600f7df3b2be40000
14:48:39,187 DEBUG SessionFactoryImpl:497 - Resolving serialized SessionFactory
14:48:39,187 DEBUG SessionFactoryObjectFactory:145 - lookup: uid=13f8b386f7df3b2600f7df3b2be40000
14:48:39,203 DEBUG SessionFactoryImpl:512 - resolved SessionFactory by uid
14:48:39,203 DEBUG SessionFactoryImpl:487 - Returning a Reference to the SessionFactory
14:48:39,406 INFO HibernatePersistenceEngineBean:133 - Trying to saveObject class org.scra.emall.rule.RuleDefinition
14:48:39,468 DEBUG SessionImpl:413 - opened session
14:48:39,468 DEBUG Cascades:237 - unsaved-value strategy NULL
14:48:39,468 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null
14:48:39,468 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets
14:48:39,484 DEBUG SessionFactoryImpl:526 - prepared statement get: select ruleDef_id_seq.nextval from dual
Hibernate: select ruleDef_id_seq.nextval from dual
14:48:39,484 DEBUG SessionFactoryImpl:536 - preparing statement
14:48:39,781 DEBUG SequenceGenerator:70 - Sequence identifier generated: 15
14:48:39,781 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets
14:48:39,781 DEBUG SessionFactoryImpl:554 - closing statement
14:48:39,781 DEBUG SessionImpl:656 - saving [org.scra.emall.rule.RuleDefinition#15]
14:48:39,781 DEBUG SessionImpl:2011 - flushing session
14:48:39,796 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections
14:48:39,796 DEBUG SessionImpl:2397 - Processing unreferenced collections
14:48:39,796 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates
14:48:39,796 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
14:48:39,796 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
14:48:39,796 DEBUG SessionImpl:2058 - executing flush
14:48:39,796 DEBUG SessionImpl:2428 - post flush
14:48:39,796 DEBUG SessionImpl:435 - closing session
14:48:39,796 DEBUG SessionImpl:2930 - disconnecting session
14:48:39,812 DEBUG SessionImpl:447 - transaction completion
14:49:19,812 INFO HibernatePersistenceEngineBean:133 - Trying to saveObject class org.scra.emall.rule.RuleDefinition
14:49:19,812 DEBUG SessionImpl:413 - opened session
14:49:19,812 DEBUG Cascades:237 - unsaved-value strategy NULL
14:49:19,812 DEBUG SessionImpl:1201 - saveOrUpdate() unsaved instance with id: null
14:49:19,812 DEBUG BatcherImpl:166 - about to open: 0 open PreparedStatements, 0 open ResultSets
14:49:19,828 DEBUG SessionFactoryImpl:526 - prepared statement get: select ruleDef_id_seq.nextval from dual
Hibernate: select ruleDef_id_seq.nextval from dual
14:49:19,828 DEBUG SessionFactoryImpl:536 - preparing statement
14:49:19,843 DEBUG SequenceGenerator:70 - Sequence identifier generated: 16
14:49:19,843 DEBUG BatcherImpl:173 - done closing: 0 open PreparedStatements, 0 open ResultSets
14:49:19,843 DEBUG SessionFactoryImpl:554 - closing statement
14:49:19,843 DEBUG SessionImpl:656 - saving [org.scra.emall.rule.RuleDefinition#16]
14:49:19,843 DEBUG SessionImpl:2011 - flushing session
14:49:19,843 DEBUG SessionImpl:2113 - Flushing entities and processing referenced collections
14:49:19,843 DEBUG SessionImpl:2397 - Processing unreferenced collections
14:49:19,843 DEBUG SessionImpl:2408 - Scheduling collection removes/(re)creates/updates
14:49:19,843 DEBUG SessionImpl:2023 - Flushed: 0 insertions, 0 updates, 0 deletions to 0 objects
14:49:19,843 DEBUG SessionImpl:2028 - Flushed: 0 (re)creations, 0 updates, 0 removals to 0 collections
14:49:19,843 DEBUG SessionImpl:2058 - executing flush
14:49:19,843 DEBUG SessionImpl:2428 - post flush
14:49:19,843 DEBUG SessionImpl:435 - closing session
14:49:19,843 DEBUG SessionImpl:2930 - disconnecting session
14:49:19,843 DEBUG SessionImpl:447 - transaction completion
14:53:39,734 DEBUG SessionImpl:2981 - running Session.finalize()