-->
These old forums are deprecated now and set to read-only. We are waiting for you on our new forums!
More modern, Discourse-based and with GitHub/Google/Twitter authentication built-in.

View unanswered posts | View active topics


Board index » Hibernate & Java Persistence » Hibernate Users

All times are UTC - 5 hours [ DST ]


Forum locked This topic is locked, you cannot edit posts or make further replies.  Page 1 of 1
  [ 4 posts ] 
  Previous topic | Next topic 
Author Message
gcrider
 Post subject: composite-id won't insert or update
PostPosted: Fri May 19, 2006 12:17 am 
Newbie

Joined: Thu May 18, 2006 11:52 pm
Posts: 3
I am accessing a legacy table with 3 columns that comprise the primary key. I can successfully read from the table, but whenever I call save() or saveOrUpdate() the corresponding insert or update never occurs. There are no exceptions, and Hibernate (for the case of an insert) knows that my entity object is transient.

Is there something special that I need to do in order to use a composite-id mapping for saves? I already updated from Hibernate 3.0, which exhibited problems with the composite-id upon executing a query. Using Hibernate 3.2 didn't yield any change either, so I stuck with version 3.1.3. I'm sure that I've configured something wrong, but this should be really simple.

Any help is greatly appreciated. Thanks.

Hibernate version: 3.1.3

Mapping documents:

This is the mapping file being used:

Code:
<?xml version="1.0"?>
<!DOCTYPE hibernate-mapping PUBLIC
    "-//Hibernate/Hibernate Mapping DTD 3.0//EN"
    "http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd" >
   
<hibernate-mapping
   package="org.ntca.apps.memprof.domain.model"   
>

<class
    name="ActDtl"
    table="actdtl"
>

   <!-- CONTROL FIELDS -->
   
   <composite-id
      name="id"
      class="ActDtl$Id">
       <key-property name="masterId" type="java.lang.Long" column="ADIDNO"/>
      <key-property name="activityCode" column="ADACTC" length="6"/>
      <key-property name="recordStatus" column="ADRDST" length="1"/>
   </composite-id>

    <version
        name="version"
        type="java.lang.Long"
        column="VERSION"
    />

   <!-- DATA FIELDS -->

    <property
        name="activityType"
        type="java.lang.String"
        column="ADACTY"
        not-null="false"
        length="2"
    />
   
    <property
       name="contactSequenceNumber"
       type="java.lang.Integer"
       column="ADCNSQ"
       not-null="false"
    />
   
    <property
       name="comment"
       type="java.lang.String"
       column="ADCMNT"
       not-null="false"
       length="30"
    />

   <property
      name="activityDate1"
      type="java.lang.Long"
      column="ADADT1"
      not-null="false"
   />

   <property
      name="activityDate2"
      type="java.lang.Long"
      column="ADADT2"
      not-null="false"
   />

   <property
      name="auxAddressIdCode"
      type="java.lang.String"
      column="ADADRNO"
      not-null="false"
      length="1"
   />
   
    <property
       name="quantity1"
       type="java.lang.Integer"
       column="ADQTY1"
       not-null="false"
    />
   
    <property
       name="quantity2"
       type="java.lang.Integer"
       column="ADQTY2"
       not-null="false"
    />
   
    <property
       name="quantity3"
       type="java.lang.Integer"
       column="ADQTY3"
       not-null="false"
    />

   <property
      name="positionCode"
      type="java.lang.String"
      column="ADPOSC"
      not-null="false"
      length="4"
   />
   
</class>

<query name="findCompanyDetails">
   from ActDtl a
   where ( a.id.masterId = :companyId )
</query>

</hibernate-mapping>


Debug level Hibernate log excerpt:

This is the log showing an initial save (insert) followed by a lookup (which successfully returns 3 objects but not the newly added 4th ojbect that should have been inserted); those 3 objects are updated and saved. None of those saves work, or make it to the database (no SQL is generated):

Quote:
2006/05/18 23:29:03.677 [INFO ] <main> (ActDtlDAOTest.testSaveIndividualCompany:62) - ******* TEST CASE: Adding new detail
2006/05/18 23:29:03.687 [INFO ] <main> (ActDtlDAOTest.testSaveIndividualCompany:80) - ******* New Detail: ActDtl::[id=Id::[masterid=1504,recordstatus=A,activitycode=CB2006],version=null,contactsequencenumber=0,activitytype=CB,comment=Mr. Crider,activitydate1=?,activitydate2=?,auxaddressidcode=,quantity1=0,quantity2=0,quantity3=0,positioncode=CB]
2006/05/18 23:29:03.918 [DEBUG] <main> (SessionImpl.<init>:219) - opened session at timestamp: 4702246272356352
2006/05/18 23:29:03.928 [DEBUG] <main> (VersionValue$1.isUnsaved:44) - version unsaved-value strategy UNDEFINED
2006/05/18 23:29:03.928 [DEBUG] <main> (AbstractSaveEventListener.getEntityState:462) - transient instance of: org.ntca.apps.memprof.domain.model.ActDtl
2006/05/18 23:29:03.938 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.entityIsTransient:161) - saving transient instance
2006/05/18 23:29:03.938 [DEBUG] <main> (AbstractSaveEventListener.saveWithGeneratedId:106) - generated identifier: component[masterId,activityCode,recordStatus]{activityCode=CB2006, recordStatus=A, masterId=1504}, using strategy: org.hibernate.id.Assigned
2006/05/18 23:29:26.778 [DEBUG] <main> (AbstractSaveEventListener.performSave:139) - saving [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2006, recordStatus=A, masterId=1504}]
2006/05/18 23:33:54.513 [DEBUG] <main> (Versioning.seed:42) - Seeding: 0
2006/05/18 23:39:02.158 [INFO ] <main> (ActDtlDAOTest.testSaveIndividualCompany:84) - ******* TEST CASE: Retrieving changes to CompanyId(1504)
2006/05/18 23:41:08.147 [DEBUG] <main> (SessionImpl.<init>:219) - opened session at timestamp: 4702249239130112
2006/05/18 23:41:08.167 [DEBUG] <main> (QueryPlanCache.getHQLQueryPlan:75) - located HQL query plan in cache (
from ActDtl a
where ( a.id.masterId = :companyId )
)
2006/05/18 23:41:08.177 [DEBUG] <main> (QueryPlanCache.getHQLQueryPlan:75) - located HQL query plan in cache (
from ActDtl a
where ( a.id.masterId = :companyId )
)
2006/05/18 23:41:08.187 [DEBUG] <main> (HQLQueryPlan.performList:148) - find:
from ActDtl a
where ( a.id.masterId = :companyId )

2006/05/18 23:41:08.187 [DEBUG] <main> (QueryParameters.traceParameters:262) - named parameters: {companyId=1504}
2006/05/18 23:41:08.197 [DEBUG] <main> (AbstractBatcher.logOpenPreparedStatement:311) - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006/05/18 23:41:08.207 [DEBUG] <main> (ConnectionManager.openConnection:415) - opening JDBC connection
2006/05/18 23:41:08.207 [DEBUG] <main> (AbstractBatcher.log:346) - select actdtl0_.ADIDNO as ADIDNO1_, actdtl0_.ADACTC as ADACTC1_, actdtl0_.ADRDST as ADRDST1_, actdtl0_.VERSION as VERSION1_, actdtl0_.ADACTY as ADACTY1_, actdtl0_.ADCNSQ as ADCNSQ1_, actdtl0_.ADCMNT as ADCMNT1_, actdtl0_.ADADT1 as ADADT8_1_, actdtl0_.ADADT2 as ADADT9_1_, actdtl0_.ADADRNO as ADADRNO1_, actdtl0_.ADQTY1 as ADQTY11_1_, actdtl0_.ADQTY2 as ADQTY12_1_, actdtl0_.ADQTY3 as ADQTY13_1_, actdtl0_.ADPOSC as ADPOSC1_ from actdtl actdtl0_ where actdtl0_.ADIDNO=?
Hibernate: select actdtl0_.ADIDNO as ADIDNO1_, actdtl0_.ADACTC as ADACTC1_, actdtl0_.ADRDST as ADRDST1_, actdtl0_.VERSION as VERSION1_, actdtl0_.ADACTY as ADACTY1_, actdtl0_.ADCNSQ as ADCNSQ1_, actdtl0_.ADCMNT as ADCMNT1_, actdtl0_.ADADT1 as ADADT8_1_, actdtl0_.ADADT2 as ADADT9_1_, actdtl0_.ADADRNO as ADADRNO1_, actdtl0_.ADQTY1 as ADQTY11_1_, actdtl0_.ADQTY2 as ADQTY12_1_, actdtl0_.ADQTY3 as ADQTY13_1_, actdtl0_.ADPOSC as ADPOSC1_ from actdtl actdtl0_ where actdtl0_.ADIDNO=?
2006/05/18 23:41:08.217 [DEBUG] <main> (AbstractBatcher.getPreparedStatement:424) - preparing statement
2006/05/18 23:41:08.277 [DEBUG] <main> (QueryLoader.bindNamedParameters:485) - bindNamedParameters() 1504 -> companyId [1]
2006/05/18 23:41:08.287 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding '1504' to parameter: 1
2006/05/18 23:41:08.297 [DEBUG] <main> (AbstractBatcher.logOpenResults:327) - about to open ResultSet (open ResultSets: 0, globally: 0)
2006/05/18 23:41:08.307 [DEBUG] <main> (Loader.doQuery:682) - processing result set
2006/05/18 23:41:08.307 [DEBUG] <main> (Loader.doQuery:687) - result set row: 0
2006/05/18 23:41:08.337 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '1504' as column: ADIDNO1_
2006/05/18 23:41:08.347 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB2003' as column: ADACTC1_
2006/05/18 23:41:08.347 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'A' as column: ADRDST1_
2006/05/18 23:41:08.357 [DEBUG] <main> (Loader.getRow:1164) - result row: EntityKey[org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2003, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.367 [DEBUG] <main> (Loader.loadFromResultSet:1347) - Initializing object from ResultSet: [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2003, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.377 [DEBUG] <main> (AbstractEntityPersister.hydrate:1860) - Hydrating entity: [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2003, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.377 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: VERSION1_
2006/05/18 23:41:08.397 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADACTY1_
2006/05/18 23:41:08.407 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '1' as column: ADCNSQ1_
2006/05/18 23:41:08.417 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'Mr. John Hayes Vaughan' as column: ADCMNT1_
2006/05/18 23:41:08.417 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT8_1_
2006/05/18 23:41:08.427 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT9_1_
2006/05/18 23:41:08.427 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADADRNO1_
2006/05/18 23:41:08.437 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY11_1_
2006/05/18 23:41:08.477 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY12_1_
2006/05/18 23:41:08.487 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY13_1_
2006/05/18 23:41:08.487 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADPOSC1_
2006/05/18 23:41:08.497 [DEBUG] <main> (TwoPhaseLoad.postHydrate:75) - Version: 0
2006/05/18 23:41:08.497 [DEBUG] <main> (Loader.doQuery:687) - result set row: 1
2006/05/18 23:41:08.517 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '1504' as column: ADIDNO1_
2006/05/18 23:41:08.527 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB2004' as column: ADACTC1_
2006/05/18 23:41:08.537 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'A' as column: ADRDST1_
2006/05/18 23:41:08.537 [DEBUG] <main> (Loader.getRow:1164) - result row: EntityKey[org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2004, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.547 [DEBUG] <main> (Loader.loadFromResultSet:1347) - Initializing object from ResultSet: [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2004, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.547 [DEBUG] <main> (AbstractEntityPersister.hydrate:1860) - Hydrating entity: [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2004, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.557 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: VERSION1_
2006/05/18 23:41:08.567 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADACTY1_
2006/05/18 23:41:08.587 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '1' as column: ADCNSQ1_
2006/05/18 23:41:08.587 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'Mr. John Hayes Vaughan' as column: ADCMNT1_
2006/05/18 23:41:08.597 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT8_1_
2006/05/18 23:41:08.607 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT9_1_
2006/05/18 23:41:08.607 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADADRNO1_
2006/05/18 23:41:08.617 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY11_1_
2006/05/18 23:41:08.617 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY12_1_
2006/05/18 23:41:08.637 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY13_1_
2006/05/18 23:41:08.637 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADPOSC1_
2006/05/18 23:41:08.647 [DEBUG] <main> (TwoPhaseLoad.postHydrate:75) - Version: 0
2006/05/18 23:41:08.647 [DEBUG] <main> (Loader.doQuery:687) - result set row: 2
2006/05/18 23:41:08.658 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '1504' as column: ADIDNO1_
2006/05/18 23:41:08.658 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB2005' as column: ADACTC1_
2006/05/18 23:41:08.668 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'A' as column: ADRDST1_
2006/05/18 23:41:08.668 [DEBUG] <main> (Loader.getRow:1164) - result row: EntityKey[org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2005, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.688 [DEBUG] <main> (Loader.loadFromResultSet:1347) - Initializing object from ResultSet: [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2005, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.688 [DEBUG] <main> (AbstractEntityPersister.hydrate:1860) - Hydrating entity: [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2005, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.698 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: VERSION1_
2006/05/18 23:41:08.708 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADACTY1_
2006/05/18 23:41:08.708 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADCNSQ1_
2006/05/18 23:41:08.718 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADCMNT1_
2006/05/18 23:41:08.728 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT8_1_
2006/05/18 23:41:08.738 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT9_1_
2006/05/18 23:41:08.738 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADADRNO1_
2006/05/18 23:41:08.748 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY11_1_
2006/05/18 23:41:08.748 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY12_1_
2006/05/18 23:41:08.758 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY13_1_
2006/05/18 23:41:08.758 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADPOSC1_
2006/05/18 23:41:08.768 [DEBUG] <main> (TwoPhaseLoad.postHydrate:75) - Version: 0
2006/05/18 23:41:08.798 [DEBUG] <main> (Loader.doQuery:709) - done processing result set (3 rows)
2006/05/18 23:41:08.798 [DEBUG] <main> (AbstractBatcher.logCloseResults:334) - about to close ResultSet (open ResultSets: 1, globally: 1)
2006/05/18 23:41:08.808 [DEBUG] <main> (AbstractBatcher.logClosePreparedStatement:319) - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006/05/18 23:41:08.808 [DEBUG] <main> (AbstractBatcher.closePreparedStatement:470) - closing statement
2006/05/18 23:41:08.818 [DEBUG] <main> (ConnectionManager.aggressiveRelease:398) - aggressively releasing JDBC connection
2006/05/18 23:41:08.828 [DEBUG] <main> (ConnectionManager.closeConnection:435) - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006/05/18 23:41:08.858 [DEBUG] <main> (Loader.initializeEntitiesAndCollections:839) - total objects hydrated: 3
2006/05/18 23:41:08.858 [DEBUG] <main> (TwoPhaseLoad.initializeEntity:107) - resolving associations for [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2003, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.868 [DEBUG] <main> (TwoPhaseLoad.initializeEntity:206) - done materializing entity [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2003, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.868 [DEBUG] <main> (TwoPhaseLoad.initializeEntity:107) - resolving associations for [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2004, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.888 [DEBUG] <main> (TwoPhaseLoad.initializeEntity:206) - done materializing entity [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2004, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.888 [DEBUG] <main> (TwoPhaseLoad.initializeEntity:107) - resolving associations for [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2005, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.928 [DEBUG] <main> (TwoPhaseLoad.initializeEntity:206) - done materializing entity [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2005, recordStatus=A, masterId=1504}]
2006/05/18 23:41:08.928 [DEBUG] <main> (StatefulPersistenceContext.initializeNonLazyCollections:748) - initializing non-lazy collections
2006/05/18 23:41:08.938 [DEBUG] <main> (JDBCContext.afterNontransactionalQuery:217) - after autocommit
2006/05/18 23:41:08.938 [DEBUG] <main> (ConnectionManager.aggressiveRelease:398) - aggressively releasing JDBC connection
2006/05/18 23:41:08.948 [DEBUG] <main> (SessionImpl.afterTransactionCompletion:417) - after transaction completion
2006/05/18 23:41:08.958 [INFO ] <main> (ActDtlDAOTest.testSaveIndividualCompany:93) - ActDtl: ActDtl::[id=Id::[masterid=1504,recordstatus=A,activitycode=CB2003],version=0,contactsequencenumber=1,activitytype=CB,comment=Changed,activitydate1=?,activitydate2=?,auxaddressidcode=,quantity1=0,quantity2=0,quantity3=0,positioncode=CB]
2006/05/18 23:41:08.958 [INFO ] <main> (ActDtlDAOTest.testSaveIndividualCompany:93) - ActDtl: ActDtl::[id=Id::[masterid=1504,recordstatus=A,activitycode=CB2004],version=0,contactsequencenumber=1,activitytype=CB,comment=Changed,activitydate1=?,activitydate2=?,auxaddressidcode=,quantity1=0,quantity2=0,quantity3=0,positioncode=CB]
2006/05/18 23:41:08.978 [INFO ] <main> (ActDtlDAOTest.testSaveIndividualCompany:93) - ActDtl: ActDtl::[id=Id::[masterid=1504,recordstatus=A,activitycode=CB2005],version=0,contactsequencenumber=0,activitytype=CB,comment=Changed,activitydate1=?,activitydate2=?,auxaddressidcode=,quantity1=0,quantity2=0,quantity3=0,positioncode=]
2006/05/18 23:41:08.988 [INFO ] <main> (ActDtlDAOTest.testSaveIndividualCompany:96) - ******* TEST CASE: Batch save for CompanyId(1504)
2006/05/18 23:41:08.988 [DEBUG] <main> (SessionImpl.<init>:219) - opened session at timestamp: 4702249242574848
2006/05/18 23:41:08.998 [DEBUG] <main> (VersionValue$1.isUnsaved:44) - version unsaved-value strategy UNDEFINED
2006/05/18 23:41:08.998 [DEBUG] <main> (IdentifierValue$4.isUnsaved:77) - id unsaved-value strategy UNDEFINED
2006/05/18 23:41:09.008 [DEBUG] <main> (AbstractEntityPersister.getDatabaseSnapshot:975) - Getting current persistent state for: [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2003, recordStatus=A, masterId=1504}]
2006/05/18 23:41:09.008 [DEBUG] <main> (AbstractBatcher.logOpenPreparedStatement:311) - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006/05/18 23:41:09.018 [DEBUG] <main> (ConnectionManager.openConnection:415) - opening JDBC connection
2006/05/18 23:41:09.018 [DEBUG] <main> (AbstractBatcher.log:346) - select actdtl_.ADIDNO, actdtl_.ADACTC, actdtl_.ADRDST, actdtl_.VERSION as VERSION1_, actdtl_.ADACTY as ADACTY1_, actdtl_.ADCNSQ as ADCNSQ1_, actdtl_.ADCMNT as ADCMNT1_, actdtl_.ADADT1 as ADADT8_1_, actdtl_.ADADT2 as ADADT9_1_, actdtl_.ADADRNO as ADADRNO1_, actdtl_.ADQTY1 as ADQTY11_1_, actdtl_.ADQTY2 as ADQTY12_1_, actdtl_.ADQTY3 as ADQTY13_1_, actdtl_.ADPOSC as ADPOSC1_ from actdtl actdtl_ where actdtl_.ADIDNO=? and actdtl_.ADACTC=? and actdtl_.ADRDST=?
Hibernate: select actdtl_.ADIDNO, actdtl_.ADACTC, actdtl_.ADRDST, actdtl_.VERSION as VERSION1_, actdtl_.ADACTY as ADACTY1_, actdtl_.ADCNSQ as ADCNSQ1_, actdtl_.ADCMNT as ADCMNT1_, actdtl_.ADADT1 as ADADT8_1_, actdtl_.ADADT2 as ADADT9_1_, actdtl_.ADADRNO as ADADRNO1_, actdtl_.ADQTY1 as ADQTY11_1_, actdtl_.ADQTY2 as ADQTY12_1_, actdtl_.ADQTY3 as ADQTY13_1_, actdtl_.ADPOSC as ADPOSC1_ from actdtl actdtl_ where actdtl_.ADIDNO=? and actdtl_.ADACTC=? and actdtl_.ADRDST=?
2006/05/18 23:41:09.048 [DEBUG] <main> (AbstractBatcher.getPreparedStatement:424) - preparing statement
2006/05/18 23:41:09.058 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding '1504' to parameter: 1
2006/05/18 23:41:09.068 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding 'CB2003' to parameter: 2
2006/05/18 23:41:09.068 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding 'A' to parameter: 3
2006/05/18 23:41:09.098 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: VERSION1_
2006/05/18 23:41:09.108 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADACTY1_
2006/05/18 23:41:09.108 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '1' as column: ADCNSQ1_
2006/05/18 23:41:09.118 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'Mr. John Hayes Vaughan' as column: ADCMNT1_
2006/05/18 23:41:09.118 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT8_1_
2006/05/18 23:41:09.128 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT9_1_
2006/05/18 23:41:09.128 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADADRNO1_
2006/05/18 23:41:09.138 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY11_1_
2006/05/18 23:41:09.168 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY12_1_
2006/05/18 23:41:09.168 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY13_1_
2006/05/18 23:41:09.178 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADPOSC1_
2006/05/18 23:41:09.188 [DEBUG] <main> (AbstractBatcher.logClosePreparedStatement:319) - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006/05/18 23:41:09.188 [DEBUG] <main> (AbstractBatcher.closePreparedStatement:470) - closing statement
2006/05/18 23:41:09.188 [DEBUG] <main> (ConnectionManager.aggressiveRelease:398) - aggressively releasing JDBC connection
2006/05/18 23:41:09.198 [DEBUG] <main> (ConnectionManager.closeConnection:435) - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006/05/18 23:41:09.218 [DEBUG] <main> (AbstractSaveEventListener.getEntityState:471) - detached instance of: org.ntca.apps.memprof.domain.model.ActDtl
2006/05/18 23:41:09.218 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.entityIsDetached:202) - updating detached instance
2006/05/18 23:41:09.228 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.performUpdate:248) - updating [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2003, recordStatus=A, masterId=1504}]
2006/05/18 23:41:09.238 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.performUpdate:297) - updating [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2003, recordStatus=A, masterId=1504}]
2006/05/18 23:41:09.248 [DEBUG] <main> (SessionImpl.<init>:219) - opened session at timestamp: 4702249243639808
2006/05/18 23:41:09.258 [DEBUG] <main> (VersionValue$1.isUnsaved:44) - version unsaved-value strategy UNDEFINED
2006/05/18 23:41:09.258 [DEBUG] <main> (IdentifierValue$4.isUnsaved:77) - id unsaved-value strategy UNDEFINED
2006/05/18 23:41:09.268 [DEBUG] <main> (AbstractEntityPersister.getDatabaseSnapshot:975) - Getting current persistent state for: [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2004, recordStatus=A, masterId=1504}]
2006/05/18 23:41:09.288 [DEBUG] <main> (AbstractBatcher.logOpenPreparedStatement:311) - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006/05/18 23:41:09.288 [DEBUG] <main> (ConnectionManager.openConnection:415) - opening JDBC connection
2006/05/18 23:41:09.298 [DEBUG] <main> (AbstractBatcher.log:346) - select actdtl_.ADIDNO, actdtl_.ADACTC, actdtl_.ADRDST, actdtl_.VERSION as VERSION1_, actdtl_.ADACTY as ADACTY1_, actdtl_.ADCNSQ as ADCNSQ1_, actdtl_.ADCMNT as ADCMNT1_, actdtl_.ADADT1 as ADADT8_1_, actdtl_.ADADT2 as ADADT9_1_, actdtl_.ADADRNO as ADADRNO1_, actdtl_.ADQTY1 as ADQTY11_1_, actdtl_.ADQTY2 as ADQTY12_1_, actdtl_.ADQTY3 as ADQTY13_1_, actdtl_.ADPOSC as ADPOSC1_ from actdtl actdtl_ where actdtl_.ADIDNO=? and actdtl_.ADACTC=? and actdtl_.ADRDST=?
Hibernate: select actdtl_.ADIDNO, actdtl_.ADACTC, actdtl_.ADRDST, actdtl_.VERSION as VERSION1_, actdtl_.ADACTY as ADACTY1_, actdtl_.ADCNSQ as ADCNSQ1_, actdtl_.ADCMNT as ADCMNT1_, actdtl_.ADADT1 as ADADT8_1_, actdtl_.ADADT2 as ADADT9_1_, actdtl_.ADADRNO as ADADRNO1_, actdtl_.ADQTY1 as ADQTY11_1_, actdtl_.ADQTY2 as ADQTY12_1_, actdtl_.ADQTY3 as ADQTY13_1_, actdtl_.ADPOSC as ADPOSC1_ from actdtl actdtl_ where actdtl_.ADIDNO=? and actdtl_.ADACTC=? and actdtl_.ADRDST=?
2006/05/18 23:41:09.308 [DEBUG] <main> (AbstractBatcher.getPreparedStatement:424) - preparing statement
2006/05/18 23:41:09.338 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding '1504' to parameter: 1
2006/05/18 23:41:09.338 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding 'CB2004' to parameter: 2
2006/05/18 23:41:09.338 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding 'A' to parameter: 3
2006/05/18 23:41:09.359 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: VERSION1_
2006/05/18 23:41:09.369 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADACTY1_
2006/05/18 23:41:09.369 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '1' as column: ADCNSQ1_
2006/05/18 23:41:09.389 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'Mr. John Hayes Vaughan' as column: ADCMNT1_
2006/05/18 23:41:09.389 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT8_1_
2006/05/18 23:41:09.399 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT9_1_
2006/05/18 23:41:09.399 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADADRNO1_
2006/05/18 23:41:09.409 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY11_1_
2006/05/18 23:41:09.409 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY12_1_
2006/05/18 23:41:09.419 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY13_1_
2006/05/18 23:41:09.419 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADPOSC1_
2006/05/18 23:41:09.429 [DEBUG] <main> (AbstractBatcher.logClosePreparedStatement:319) - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006/05/18 23:41:09.429 [DEBUG] <main> (AbstractBatcher.closePreparedStatement:470) - closing statement
2006/05/18 23:41:09.439 [DEBUG] <main> (ConnectionManager.aggressiveRelease:398) - aggressively releasing JDBC connection
2006/05/18 23:41:09.449 [DEBUG] <main> (ConnectionManager.closeConnection:435) - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006/05/18 23:41:09.449 [DEBUG] <main> (AbstractSaveEventListener.getEntityState:471) - detached instance of: org.ntca.apps.memprof.domain.model.ActDtl
2006/05/18 23:41:09.459 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.entityIsDetached:202) - updating detached instance
2006/05/18 23:41:09.459 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.performUpdate:248) - updating [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2004, recordStatus=A, masterId=1504}]
2006/05/18 23:41:09.469 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.performUpdate:297) - updating [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2004, recordStatus=A, masterId=1504}]
2006/05/18 23:41:09.469 [DEBUG] <main> (SessionImpl.<init>:219) - opened session at timestamp: 4702249244545024
2006/05/18 23:41:09.479 [DEBUG] <main> (VersionValue$1.isUnsaved:44) - version unsaved-value strategy UNDEFINED
2006/05/18 23:41:09.479 [DEBUG] <main> (IdentifierValue$4.isUnsaved:77) - id unsaved-value strategy UNDEFINED
2006/05/18 23:41:09.489 [DEBUG] <main> (AbstractEntityPersister.getDatabaseSnapshot:975) - Getting current persistent state for: [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2005, recordStatus=A, masterId=1504}]
2006/05/18 23:41:09.489 [DEBUG] <main> (AbstractBatcher.logOpenPreparedStatement:311) - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2006/05/18 23:41:09.499 [DEBUG] <main> (ConnectionManager.openConnection:415) - opening JDBC connection
2006/05/18 23:41:09.519 [DEBUG] <main> (AbstractBatcher.log:346) - select actdtl_.ADIDNO, actdtl_.ADACTC, actdtl_.ADRDST, actdtl_.VERSION as VERSION1_, actdtl_.ADACTY as ADACTY1_, actdtl_.ADCNSQ as ADCNSQ1_, actdtl_.ADCMNT as ADCMNT1_, actdtl_.ADADT1 as ADADT8_1_, actdtl_.ADADT2 as ADADT9_1_, actdtl_.ADADRNO as ADADRNO1_, actdtl_.ADQTY1 as ADQTY11_1_, actdtl_.ADQTY2 as ADQTY12_1_, actdtl_.ADQTY3 as ADQTY13_1_, actdtl_.ADPOSC as ADPOSC1_ from actdtl actdtl_ where actdtl_.ADIDNO=? and actdtl_.ADACTC=? and actdtl_.ADRDST=?
Hibernate: select actdtl_.ADIDNO, actdtl_.ADACTC, actdtl_.ADRDST, actdtl_.VERSION as VERSION1_, actdtl_.ADACTY as ADACTY1_, actdtl_.ADCNSQ as ADCNSQ1_, actdtl_.ADCMNT as ADCMNT1_, actdtl_.ADADT1 as ADADT8_1_, actdtl_.ADADT2 as ADADT9_1_, actdtl_.ADADRNO as ADADRNO1_, actdtl_.ADQTY1 as ADQTY11_1_, actdtl_.ADQTY2 as ADQTY12_1_, actdtl_.ADQTY3 as ADQTY13_1_, actdtl_.ADPOSC as ADPOSC1_ from actdtl actdtl_ where actdtl_.ADIDNO=? and actdtl_.ADACTC=? and actdtl_.ADRDST=?
2006/05/18 23:41:09.529 [DEBUG] <main> (AbstractBatcher.getPreparedStatement:424) - preparing statement
2006/05/18 23:41:09.539 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding '1504' to parameter: 1
2006/05/18 23:41:09.549 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding 'CB2005' to parameter: 2
2006/05/18 23:41:09.559 [DEBUG] <main> (NullableType.nullSafeSet:80) - binding 'A' to parameter: 3
2006/05/18 23:41:09.579 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: VERSION1_
2006/05/18 23:41:09.589 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning 'CB' as column: ADACTY1_
2006/05/18 23:41:09.589 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADCNSQ1_
2006/05/18 23:41:09.599 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADCMNT1_
2006/05/18 23:41:09.599 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT8_1_
2006/05/18 23:41:09.609 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADADT9_1_
2006/05/18 23:41:09.609 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADADRNO1_
2006/05/18 23:41:09.619 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY11_1_
2006/05/18 23:41:09.619 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY12_1_
2006/05/18 23:41:09.649 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '0' as column: ADQTY13_1_
2006/05/18 23:41:09.659 [DEBUG] <main> (NullableType.nullSafeGet:122) - returning '' as column: ADPOSC1_
2006/05/18 23:41:09.659 [DEBUG] <main> (AbstractBatcher.logClosePreparedStatement:319) - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2006/05/18 23:41:09.659 [DEBUG] <main> (AbstractBatcher.closePreparedStatement:470) - closing statement
2006/05/18 23:41:09.669 [DEBUG] <main> (ConnectionManager.aggressiveRelease:398) - aggressively releasing JDBC connection
2006/05/18 23:41:09.679 [DEBUG] <main> (ConnectionManager.closeConnection:435) - releasing JDBC connection [ (open PreparedStatements: 0, globally: 0) (open ResultSets: 0, globally: 0)]
2006/05/18 23:41:09.679 [DEBUG] <main> (AbstractSaveEventListener.getEntityState:471) - detached instance of: org.ntca.apps.memprof.domain.model.ActDtl
2006/05/18 23:41:09.719 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.entityIsDetached:202) - updating detached instance
2006/05/18 23:41:09.719 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.performUpdate:248) - updating [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2005, recordStatus=A, masterId=1504}]
2006/05/18 23:41:09.729 [DEBUG] <main> (DefaultSaveOrUpdateEventListener.performUpdate:297) - updating [org.ntca.apps.memprof.domain.model.ActDtl#component[masterId,activityCode,recordStatus]{activityCode=CB2005, recordStatus=A, masterId=1504}][/size]
Top
 Profile  
 
gcrider
 Post subject: Problem Solved
PostPosted: Fri May 19, 2006 1:39 am 
Newbie

Joined: Thu May 18, 2006 11:52 pm
Posts: 3
I'm an idiot. As it turns out, the problem was with Spring, which I'm using for base dao support and transaction management. The configuration was off in one key area, so the Hibernate session was not doing it's thing correctly.

Sorry about that.
Top
 Profile  
 
jruffin
 Post subject:
PostPosted: Sun May 21, 2006 6:23 pm 
Regular
Regular

Joined: Sat Nov 06, 2004 5:20 pm
Posts: 54
Location: Collierville, TN
Quote:
"...the problem was with Spring, which I'm using for base dao support and transaction management. The configuration was off in one key area, so the Hibernate session was not doing it's thing correctly."


Please elaborate. What was the configuration problem & solution? I'm having a similar problem.
Top
 Profile  
 
gcrider
 Post subject: Spring Config Solution
PostPosted: Sun May 21, 2006 6:42 pm 
Newbie

Joined: Thu May 18, 2006 11:52 pm
Posts: 3
I am using the Spring class:

Code:
org.springframework.orm.hibernate3.support.HibernateDaoSupport


as the base class of my DAO. I am also using MySql 4.1 as the database.

In order to get an update/insert, I included the Spring configuration below in my application context. This was just for unit test purposes. For my production version, I will have a service class used to encapsulate the ORM operations that need to be included in one transaction. Therefore the bean defintions below will be different to reflect the service class and specific methods. For unit testing, I just made a wholesale transaction declaration for all of my DAO classes, regardless of the method being used.

Without this, Spring must not have started the transaction, and consequently, Hibernate did not perform the insert/update.

Code:
<beans>

    <!--
     ! memprofTxManager:
     !
     ! Transaction manager centers around the Hibernate session factory.
      -->
   <bean id="memprofTxManager"
        class="org.springframework.orm.hibernate3.HibernateTransactionManager">
        <property name="sessionFactory"><ref bean="memprofSessionFactory"/></property>
    </bean>
   
   <!--
    ! memprofTxInterceptor:
    !
    ! Transaction interceptor ties the transaction manager to the attributes used to
    ! determine which calls to intercept, and wrap a transaction around (according to
    ! the attributes).
     -->
   <bean id="memprofTxInterceptor" class="org.springframework.transaction.interceptor.TransactionInterceptor">
      <property name="transactionManager"><ref bean="memprofTxManager"/></property>
      <property name="transactionAttributeSource"><ref bean="memprofTxAttributes"/></property>
   </bean>

    <!--
     ! memprofTxAttributes:
     !
     ! These are the attributes that define what transaction attributes to associate with various
     ! defined names.
      -->
   <bean id="memprofTxAttributes" class="org.springframework.transaction.interceptor.NameMatchTransactionAttributeSource">
      <property name="properties">
          <props>
              <!-- All Methods -->
             <prop key="*">PROPAGATION_REQUIRES_NEW</prop>
         </props>
      </property>
   </bean>

    <!--
     ! memprofTxAutoProxyCreator:
     !
     ! Proxies the specified bean names with an interceptor. This ties the transaction manager
     ! with a target to manage. The proxied beans are given the same name as the original
     ! one.
      -->
   <bean id="memprofTxAutoProxyCreator" class="org.springframework.aop.framework.autoproxy.BeanNameAutoProxyCreator">
      <property name="optimize"><value>true</value></property>
      <property name="interceptorNames"><value>memprofTxInterceptor</value></property>
      <!--
       ! Proxy all of the DAO beans. For complex transactions involving multiple DAO's or calls,
       ! a Service needs to be created and added to this proxy.
        -->
      <property name="beanNames">
         <list>
            <!-- All Service are proxied -->
            <value>*DAO</value>
         </list>
      </property>
   </bean>

</beans>
Top
 Profile  
 
Display posts from previous:  Sort by  
Forum locked This topic is locked, you cannot edit posts or make further replies.  Page 1 of 1
  [ 4 posts ] 

Board index » Hibernate & Java Persistence » Hibernate Users

All times are UTC - 5 hours [ DST ]

You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum

Search for:
© Copyright 2014, Red Hat Inc. All rights reserved. JBoss and Hibernate are registered trademarks and servicemarks of Red Hat, Inc.