Hi,
I've turned on as many loggers as I can and have a some output here. The application seems to be hanging when I try to add a collection i.e a packer.
thanks again for any help
Paul
Code:
15:26:18,031 DEBUG HibernateUtil:57 - HibernateUtil::Opening new Hibernate Session
15:26:18,031 DEBUG HibernateUtil:59 - HibernateUtil::Added Session to current Thread
15:26:21,515 DEBUG HibernateUtil:104 - HibernateUtil::Starting new Hibernate Transaction
15:26:22,937 DEBUG JDBCTransaction:54 - begin
15:26:22,937 DEBUG ConnectionManager:415 - opening JDBC connection
15:26:22,937 DEBUG DriverManagerConnectionProvider:93 - total checked-out connections: 0
15:26:22,937 DEBUG DriverManagerConnectionProvider:99 - using pooled JDBC connection, pool size: 0
15:26:22,937 DEBUG JDBCContext:159 - no active transaction, could not register Synchronization
15:26:22,937 DEBUG JDBCTransaction:59 - current autocommit status: false
15:26:22,937 DEBUG JDBCContext:194 - after transaction begin
15:26:22,937 DEBUG HibernateUtil:108 - HibernateUtil::Added Hibernate Transaction to local thread:: tx is Active= true
15:26:22,953 DEBUG DefaultSaveOrUpdateEventListener:161 - saving transient instance
15:26:22,953 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
15:26:22,953 DEBUG SQL:346 -
select
dpcs.CERTIFICATE_ID_SEQ.nextval
from
dual
06/11/29 15:26:22 Hibernate:
select
dpcs.CERTIFICATE_ID_SEQ.nextval
from
dual
15:26:22,953 DEBUG AbstractBatcher:424 - preparing statement
15:26:22,968 DEBUG SequenceGenerator:82 - Sequence identifier generated: 282
15:26:22,968 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
15:26:22,968 DEBUG AbstractBatcher:470 - closing statement
15:26:22,968 DEBUG AbstractSaveEventListener:106 - generated identifier: 282, using strategy: org.hibernate.id.SequenceGenerator
15:26:22,968 DEBUG AbstractSaveEventListener:139 - saving [ie.gov.agriculture.dpcs.cert.Certificate#282]
15:26:22,968 DEBUG Cascade:237 - processing cascade ACTION_SAVE_UPDATE for: ie.gov.agriculture.dpcs.cert.Certificate
15:26:22,968 DEBUG Cascade:259 - done processing cascade ACTION_SAVE_UPDATE for: ie.gov.agriculture.dpcs.cert.Certificate
15:26:23,031 DEBUG WrapVisitor:87 - Wrapped collection in role: ie.gov.agriculture.dpcs.cert.Certificate.packers
15:26:23,046 DEBUG Cascade:237 - processing cascade ACTION_SAVE_UPDATE for: ie.gov.agriculture.dpcs.cert.Certificate
15:26:23,046 DEBUG Cascade:285 - cascade ACTION_SAVE_UPDATE for collection: ie.gov.agriculture.dpcs.cert.Certificate.packers
15:26:23,046 DEBUG CascadingAction:133 - cascading to saveOrUpdate: ie.gov.agriculture.dpcs.cert.Packer
15:26:23,046 DEBUG IdentifierValue:104 - id unsaved-value: 0
15:26:23,062 DEBUG AbstractSaveEventListener:462 - transient instance of: ie.gov.agriculture.dpcs.cert.Packer
15:26:23,062 DEBUG DefaultSaveOrUpdateEventListener:161 - saving transient instance
15:26:23,062 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
15:26:23,062 DEBUG SQL:346 -
select
dpcs.PACKER_ID_SEQ.nextval
from
dual
06/11/29 15:26:23 Hibernate:
select
dpcs.PACKER_ID_SEQ.nextval
from
dual
15:26:23,078 DEBUG AbstractBatcher:424 - preparing statement
15:26:23,078 DEBUG SequenceGenerator:82 - Sequence identifier generated: 141
15:26:23,078 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
15:26:23,078 DEBUG AbstractBatcher:470 - closing statement
15:26:23,078 DEBUG AbstractSaveEventListener:106 - generated identifier: 141, using strategy: org.hibernate.id.SequenceGenerator
15:26:23,078 DEBUG AbstractSaveEventListener:139 - saving [ie.gov.agriculture.dpcs.cert.Packer#141]
15:26:23,078 DEBUG Cascade:300 - done cascade ACTION_SAVE_UPDATE for collection: ie.gov.agriculture.dpcs.cert.Certificate.packers
15:26:23,078 DEBUG Cascade:259 - done processing cascade ACTION_SAVE_UPDATE for: ie.gov.agriculture.dpcs.cert.Certificate
15:26:23,078 DEBUG CreateCertStep2:104 - Created certificate with ID=282
15:26:23,078 DEBUG CreateCertStep2:114 - Creating Exporter Certificate: Step 2
15:26:23,078 DEBUG BaseAction:85 - Returning action forward: ForwardConfig[name=Success,path=/CreateCertTile.jsp,redirect=false,contextRelative=false,module=null]
15:26:29,703 DEBUG HibernateUtil:131 - HibernateUtil::Commiting Hibernate Transaction
15:26:35,390 DEBUG JDBCTransaction:103 - commit
15:26:39,593 DEBUG SessionImpl:332 - automatically flushing session
15:26:39,593 DEBUG AbstractFlushingEventListener:58 - flushing session
15:26:39,593 DEBUG AbstractFlushingEventListener:111 - processing flush-time cascades
15:26:39,593 DEBUG Cascade:237 - processing cascade ACTION_SAVE_UPDATE for: ie.gov.agriculture.dpcs.cert.Certificate
15:26:39,609 DEBUG Cascade:285 - cascade ACTION_SAVE_UPDATE for collection: ie.gov.agriculture.dpcs.cert.Certificate.packers
15:26:39,609 DEBUG CascadingAction:133 - cascading to saveOrUpdate: ie.gov.agriculture.dpcs.cert.Packer
15:26:39,609 DEBUG AbstractSaveEventListener:436 - persistent instance of: ie.gov.agriculture.dpcs.cert.Packer
15:26:39,609 DEBUG DefaultSaveOrUpdateEventListener:105 - ignoring persistent instance
15:26:39,624 DEBUG DefaultSaveOrUpdateEventListener:142 - object already associated with session: [ie.gov.agriculture.dpcs.cert.Packer#141]
15:26:39,624 DEBUG Cascade:300 - done cascade ACTION_SAVE_UPDATE for collection: ie.gov.agriculture.dpcs.cert.Certificate.packers
15:26:39,624 DEBUG Cascade:259 - done processing cascade ACTION_SAVE_UPDATE for: ie.gov.agriculture.dpcs.cert.Certificate
15:26:39,624 DEBUG AbstractFlushingEventListener:153 - dirty checking collections
15:26:39,640 DEBUG AbstractFlushingEventListener:170 - Flushing entities and processing referenced collections
15:26:39,656 DEBUG Collections:176 - Collection found: [ie.gov.agriculture.dpcs.cert.Certificate.packers#282], was: [<unreferenced>] (initialized)
15:26:39,656 DEBUG AbstractFlushingEventListener:209 - Processing unreferenced collections
15:26:39,671 DEBUG AbstractFlushingEventListener:223 - Scheduling collection removes/(re)creates/updates
15:26:39,687 DEBUG AbstractFlushingEventListener:85 - Flushed: 2 insertions, 0 updates, 0 deletions to 2 objects
15:26:39,687 DEBUG AbstractFlushingEventListener:91 - Flushed: 1 (re)creations, 0 updates, 0 removals to 1 collections
15:26:39,687 DEBUG Printer:83 - listing entities:
15:26:39,687 DEBUG Printer:90 - ie.gov.agriculture.dpcs.cert.Certificate{createdDate=null, issuedBy=null, certExpiryDate=null, contactDetails=, applicantBusinessId=null, plantSourceForSvs=null, approvalNo=null, applicationDateTime=29-11-2006 15:26:22, certificateNumber=null, sourceCountryExRoi=null, consignee=, consignor=, approvalDate=null, supplementaryInfo=null, other=null, destination=1, meansOfTransport=, receiptDateTime=null, dateOfDispatch=, issueDate=null, createdBy=null, placeOfDispatch=, status=null, traderRef=, countryOfOrigin=1, packers=[ie.gov.agriculture.dpcs.cert.Packer#141], typeOfPackaging=null, certificateId=282}
15:26:39,703 DEBUG Printer:90 - ie.gov.agriculture.dpcs.cert.Packer{milkRoi=false, productExpiryDate=null, genericProductName=null, packerId=141, address=null, plantSourceForSvs=null, productDescription=null, productionCodes=null, batchSerialNumber=null, approvalNumber=29-11-2006 15:26:22, manufacturerSameAsPacker=false, sourceCountryExRoi=null, netWeight=null, typeOfPackaging=null, quantity=null, name=Kerry Gold}
15:26:39,703 DEBUG AbstractFlushingEventListener:289 - executing flush
15:26:39,703 DEBUG ConnectionManager:463 - registering flush begin
15:26:39,734 DEBUG AbstractEntityPersister:2034 - Inserting entity: [ie.gov.agriculture.dpcs.cert.Certificate#282]
15:26:55,265 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
15:27:06,156 DEBUG SQL:346 -
insert
into
dpcs.DPCS_CERTIFICATE
(APPLICATION_DATE, APPLICANT_ID, DATE_APPROVED, APPROVAL_NO, CERT_EXPIRE_DATE, CONSIGNOR, CONSIGNEE, CONTACT_DETAILS, ORIGIN_COUNTRY, CREATED_DATE, CREATED_BY, DISPATCH_DATE, DESTINATION, ISSUE_DATE, ISSUED_BY, TRANSPORT, OTHER, DISPATCH_LOCATION, PLANT_SOURCE_SVS, RECEIPT_DATE, COUNTRY_SOURCE_EXROI, STATUS, SUPPLEMENTARY_INFO, TRADER_REF, TYPE_OF_PACKAGING, CERTIFICATE_NUMBER, CERTIFICATE_ID)
values
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
06/11/29 15:27:06 Hibernate:
insert
into
dpcs.DPCS_CERTIFICATE
(APPLICATION_DATE, APPLICANT_ID, DATE_APPROVED, APPROVAL_NO, CERT_EXPIRE_DATE, CONSIGNOR, CONSIGNEE, CONTACT_DETAILS, ORIGIN_COUNTRY, CREATED_DATE, CREATED_BY, DISPATCH_DATE, DESTINATION, ISSUE_DATE, ISSUED_BY, TRANSPORT, OTHER, DISPATCH_LOCATION, PLANT_SOURCE_SVS, RECEIPT_DATE, COUNTRY_SOURCE_EXROI, STATUS, SUPPLEMENTARY_INFO, TRADER_REF, TYPE_OF_PACKAGING, CERTIFICATE_NUMBER, CERTIFICATE_ID)
values
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
15:27:06,171 DEBUG AbstractBatcher:424 - preparing statement
15:27:34,812 DEBUG AbstractEntityPersister:1821 - Dehydrating entity: [ie.gov.agriculture.dpcs.cert.Certificate#282]
15:27:34,812 DEBUG StringType:80 - binding '29-11-2006 15:26:22' to parameter: 1
15:27:34,812 DEBUG StringType:73 - binding null to parameter: 2
15:27:34,812 DEBUG StringType:73 - binding null to parameter: 3
15:27:34,828 DEBUG StringType:73 - binding null to parameter: 4
15:27:34,828 DEBUG StringType:73 - binding null to parameter: 5
15:27:34,828 DEBUG StringType:80 - binding '' to parameter: 6
15:27:34,828 DEBUG StringType:80 - binding '' to parameter: 7
15:27:34,843 DEBUG StringType:80 - binding '' to parameter: 8
15:27:34,843 DEBUG StringType:80 - binding '1' to parameter: 9
15:27:34,843 DEBUG StringType:73 - binding null to parameter: 10
15:27:34,843 DEBUG StringType:73 - binding null to parameter: 11
15:27:34,859 DEBUG StringType:80 - binding '' to parameter: 12
15:27:34,859 DEBUG StringType:80 - binding '1' to parameter: 13
15:27:34,859 DEBUG StringType:73 - binding null to parameter: 14
15:27:34,874 DEBUG StringType:73 - binding null to parameter: 15
15:27:34,874 DEBUG StringType:80 - binding '' to parameter: 16
15:27:34,890 DEBUG StringType:73 - binding null to parameter: 17
15:27:34,890 DEBUG StringType:80 - binding '' to parameter: 18
15:27:34,890 DEBUG StringType:73 - binding null to parameter: 19
15:27:34,890 DEBUG StringType:73 - binding null to parameter: 20
15:27:34,906 DEBUG StringType:73 - binding null to parameter: 21
15:27:34,906 DEBUG StringType:73 - binding null to parameter: 22
15:27:34,906 DEBUG StringType:73 - binding null to parameter: 23
15:27:34,906 DEBUG StringType:80 - binding '' to parameter: 24
15:27:34,921 DEBUG StringType:73 - binding null to parameter: 25
15:27:34,921 DEBUG StringType:73 - binding null to parameter: 26
15:27:34,921 DEBUG LongType:80 - binding '282' to parameter: 27
15:27:42,609 DEBUG AbstractBatcher:28 - Adding to batch
15:28:03,749 DEBUG AbstractEntityPersister:2034 - Inserting entity: [ie.gov.agriculture.dpcs.cert.Packer#141]
15:28:14,437 DEBUG AbstractBatcher:55 - Executing batch size: 1
15:28:14,484 DEBUG AbstractBatcher:85 - success of batch update unknown: 0
15:28:14,484 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
15:28:14,484 DEBUG AbstractBatcher:470 - closing statement
15:28:15,171 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
15:28:15,999 DEBUG SQL:346 -
insert
into
dpcs.DPCS_PACKER
(APPROVAL_NUMBER, ADDRESS, BATCH_SERIAL_NO, GENERIC_PRODUCT_NAME, MANUFACTURER_IS_PACKER, MILK_SOURCE_ROI, NET_WEIGHT, NAME, PLANT_SOURCE_SVS, PRODUCTION_CODES, PRODUCT_DESCRIPTION, PRODUCT_EXPIRY_DATE, QUANTITY, SOURCE_NON_ROI_MILK, PACKAGING_TYPE, PACKER_ID)
values
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
06/11/29 15:28:16 Hibernate:
insert
into
dpcs.DPCS_PACKER
(APPROVAL_NUMBER, ADDRESS, BATCH_SERIAL_NO, GENERIC_PRODUCT_NAME, MANUFACTURER_IS_PACKER, MILK_SOURCE_ROI, NET_WEIGHT, NAME, PLANT_SOURCE_SVS, PRODUCTION_CODES, PRODUCT_DESCRIPTION, PRODUCT_EXPIRY_DATE, QUANTITY, SOURCE_NON_ROI_MILK, PACKAGING_TYPE, PACKER_ID)
values
(?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
15:28:16,015 DEBUG AbstractBatcher:424 - preparing statement
15:28:40,624 DEBUG AbstractEntityPersister:1821 - Dehydrating entity: [ie.gov.agriculture.dpcs.cert.Packer#141]
15:28:40,624 DEBUG StringType:80 - binding '29-11-2006 15:26:22' to parameter: 1
15:28:40,640 DEBUG StringType:73 - binding null to parameter: 2
15:28:40,640 DEBUG StringType:73 - binding null to parameter: 3
15:28:40,656 DEBUG StringType:73 - binding null to parameter: 4
15:28:40,656 DEBUG BooleanType:80 - binding 'false' to parameter: 5
15:28:40,656 DEBUG BooleanType:80 - binding 'false' to parameter: 6
15:28:40,656 DEBUG StringType:73 - binding null to parameter: 7
15:28:40,671 DEBUG StringType:80 - binding 'Kerry Gold' to parameter: 8
15:28:40,671 DEBUG StringType:73 - binding null to parameter: 9
15:28:40,671 DEBUG StringType:73 - binding null to parameter: 10
15:28:40,671 DEBUG StringType:73 - binding null to parameter: 11
15:28:40,687 DEBUG StringType:73 - binding null to parameter: 12
15:28:40,687 DEBUG StringType:73 - binding null to parameter: 13
15:28:40,687 DEBUG StringType:73 - binding null to parameter: 14
15:28:40,687 DEBUG StringType:73 - binding null to parameter: 15
15:28:40,703 DEBUG LongType:80 - binding '141' to parameter: 16
15:28:43,031 DEBUG AbstractBatcher:28 - Adding to batch
15:30:55,062 DEBUG AbstractBatcher:55 - Executing batch size: 1
15:32:21,859 DEBUG AbstractBatcher:85 - success of batch update unknown: 0
15:32:39,515 DEBUG AbstractBatcher:319 - about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
15:32:41,609 DEBUG AbstractBatcher:470 - closing statement
15:33:34,687 DEBUG AbstractCollectionPersister:999 - Inserting collection: [ie.gov.agriculture.dpcs.cert.Certificate.packers#282]
15:34:49,609 DEBUG AbstractBatcher:311 - about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
15:35:05,531 DEBUG SQL:346 -
insert
into
dpcs.DPCS_CERTIFICATE_PACKER_LINKS
(CERTIFICATE_ID, PACKER_ID)
values
(?, ?)
06/11/29 15:35:05 Hibernate:
insert
into
dpcs.DPCS_CERTIFICATE_PACKER_LINKS
(CERTIFICATE_ID, PACKER_ID)
values
(?, ?)
15:35:05,531 DEBUG AbstractBatcher:424 - preparing statement
15:35:38,843 DEBUG LongType:80 - binding '282' to parameter: 1
15:36:06,468 DEBUG LongType:80 - binding '141' to parameter: 2
15:36:13,187 DEBUG AbstractBatcher:28 - Adding to batch
15:36:31,171 DEBUG AbstractCollectionPersister:1045 - done inserting collection: 1 rows inserted
15:40:10,124 DEBUG AbstractBatcher:55 - Executing batch size: 1