Hibernate Community • View topic - Old Data Sometimes Returned From HQL Select

View unanswered posts | View active topics

Board index » Hibernate & Java Persistence » Hibernate Users

All times are UTC - 5 hours [ DST ]

Old Data Sometimes Returned From HQL Select

Page 1 of 1

[ 1 post ]

Previous topic | Next topic

Author

Message

greenbean

Post subject: Old Data Sometimes Returned From HQL Select

Posted: Mon Oct 29, 2007 6:22 pm

Newbie

Joined: Wed Jun 27, 2007 1:52 pm
Posts: 2

I am running into an issue where it appears that I am getting old data on rare instances when I do an HQL select. I run through transactions in a postgres database, updating their status as their are processed to prevent them from being selected again. This is done in a single thread. However, sometimes under a load the same transactions is selected again after it has been updated as processing.

The steps below are selected from the detailed log at the end of this post to show the flow. Why would the same transaction be selected again only in some cases when the system is under heavy load? The same thread is used. The database interfaces are using JTA.

1. Started XA transaction: XID:257:63736e77617332322f31363333:32

2. Sending 28981

3. Hibernate: update CENTRAL_TRANSACTIONS set ALGORITHM_TYPE_ID=?, END_DATE=?, DATABASE_TYPE_ID=?, PRIORITY=?, LAST_MODIFIED_DATE=?, PROCESSING_TYPE_ID=?, SEARCH_TYPE_ID=?, STATUS=?, FILE_LOCATION=?, NSN=?, TCN=?, REQUEST_XML=?, RESPONSE_XML=?, LANTERN_INFO_ID=?, LIVESCAN_INFO_ID=?, BUREAU_SEARCH_INFO_ID=?, BASTION_TRANSACTION_ID=?, MSS_RETRIES=?, RESPONSE_RETRIES=?, ROLLED_UP=? where ID=?

4. Ended XA transaction: XID:257:63736e77617332322f31363333:32

5. Started XA transaction: XID:257:63736e77617332322f31363433:32

6. Sending 28981

...

Hibernate version: 3.1.2

Mapping documents:

<?xml version="1.0" encoding="UTF-8"?>

<!DOCTYPE hibernate-mapping PUBLIC
"-//Hibernate/Hibernate Mapping DTD 3.0//EN"
"http://hibernate.sourceforge.net/hibernate-mapping-3.0.dtd">

<hibernate-mapping>
<class name="com.brn.moneycard.common.CentralTransaction"
table="CENTRAL_TRANSACTIONS" lazy="false">

<cache usage="read-write" />

<id name="id" column="ID" type="int">
<generator class="sequence">
<param name="sequence">CENTRAL_TRANSACTIONS_SEQ</param>

</generator>
</id>

<property name="algorithmTypeId" type="int" update="true"
insert="true" column="ALGORITHM_TYPE_ID" />

<property name="startDate" type="java.sql.Timestamp"
update="false" insert="true" column="START_DATE" />

<property name="endDate" type="java.sql.Timestamp" update="true"
insert="true" column="END_DATE" />

<property name="databaseTypeId" type="int" update="true"
insert="true" column="DATABASE_TYPE_ID" />

<property name="priority" type="int" update="true" insert="true"
column="PRIORITY" />

<property name="lastModifiedDate" type="java.sql.Timestamp"
update="true" insert="true" column="LAST_MODIFIED_DATE" />

<property name="processingTypeId" type="int" update="true"
insert="true" column="PROCESSING_TYPE_ID" />

<property name="searchTypeId" type="int" update="true"
insert="true" column="SEARCH_TYPE_ID" />

<property name="status" type="int" update="true" insert="true"
column="STATUS" />

<property name="fileLocation" type="java.lang.String"
update="true" insert="true" column="FILE_LOCATION" />

<property name="nsn" type="java.lang.String" update="true"
insert="true" column="NSN" />

<property name="tcn" type="java.lang.String" update="true"
insert="true" column="TCN" />

<set name="images" table="TRANSACTIONS_IMAGE_DATA" lazy="extra"
cascade="none" sort="unsorted">

<key column="TRANSACTION_ID"></key>

<many-to-many class="com.brn.moneycard.common.ImageInfo"
column="IMAGE_DATA_ID" outer-join="false" unique="true" />

</set>

<set name="minutiae" table="TRANSACTIONS_MINUTIAE_DATA"
lazy="extra" cascade="none" sort="unsorted">

<key column="TRANSACTION_ID"></key>

<many-to-many class="com.brn.moneycard.common.MinutiaeInfo"
column="MINUTIAE_DATA_ID" outer-join="false" unique="true" />

</set>

<property name="requestXml" type="java.lang.String"
update="true" insert="true" column="REQUEST_XML" />

<property name="responseXml" type="java.lang.String"
update="true" insert="true" column="RESPONSE_XML" />

<many-to-one name="lanternInfo" column="LANTERN_INFO_ID"
unique="true" not-null="false" />

<many-to-one name="livescanInfo" column="LIVESCAN_INFO_ID"
unique="true" not-null="false" />

<many-to-one name="bureauSearchInfo"
column="BUREAU_SEARCH_INFO_ID" unique="true" not-null="false" />

<property name="bastionTransactionId" type="java.lang.String"
update="true" insert="true" column="BASTION_TRANSACTION_ID" />

<property name="mssRetries" type="int" update="true"
insert="true" column="MSS_RETRIES" />

<property name="responseRetries" type="int" update="true"
insert="true" column="RESPONSE_RETRIES" />

<property name="rolledUp" type="java.lang.Boolean" update="true"
insert="true" column="ROLLED_UP" />





</class>

</hibernate-mapping>

Name and version of the database you are using: Postgres 8

Log output

2007-10-24 18:11:11,998 INFO [STDOUT] (Thread-145) Sending 28980
2007-10-24 18:11:11,998 INFO [STDOUT] (Thread-145) Hibernate: select count(IMAGE_DATA_ID) from TRANSACTIONS_IMAGE_DATA where TRANSACTION_ID =?
2007-10-24 18:11:12,003 INFO [STDOUT] (Thread-145) Hibernate: select count(MINUTIAE_DATA_ID) from TRANSACTIONS_MINUTIAE_DATA where TRANSACTION_ID =?
2007-10-24 18:11:12,006 INFO [STDOUT] (Thread-145) Hibernate: update CENTRAL_TRANSACTIONS set ALGORITHM_TYPE_ID=?, END_DATE=?, DATABASE_TYPE_ID=?, PRIORITY=?, LAST_MODIFIED_DATE=?, PROCESSING_TYPE_ID=?, SEARCH_TYPE_ID=?, STATUS=?, FILE_LOCATION=?, NSN=?, TCN=?, REQUEST_XML=?, RESPONSE_XML=?, LANTERN_INFO_ID=?, LIVESCAN_INFO_ID=?, BUREAU_SEARCH_INFO_ID=?, BASTION_TRANSACTION_ID=?, MSS_RETRIES=?, RESPONSE_RETRIES=?, ROLLED_UP=? where ID=?
2007-10-24 18:11:12,020 INFO [STDOUT] (Thread-145) Hibernate: select livescanin0_.ID as ID318_0_, livescanin0_.BUREAU_ID as BUREAU2_318_0_, livescanin0_.EMAIL_ADDRESS as EMAIL3_318_0_, livescanin0_.PRIORITY as PRIORITY318_0_ from LIVESCAN_INFO livescanin0_ where livescanin0_.ID=?
2007-10-24 18:11:12,021 INFO [STDOUT] (Thread-145) Hibernate: select bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_ where bureau0_.BUREAU_ID=?
2007-10-24 18:11:12,022 INFO [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) NSN: 250710028979; Transaction was successfully sent to TPFM processing queue.
2007-10-24 18:11:12,023 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,047 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,048 INFO [STDOUT] (Thread-145) Hibernate: update BUREAUS set CODE=?, PRIME=?, ROUND_ROBIN_COUNT=?, BUREAU_ID=?, ROUND_ROBIN_WEIGHT=? where ID=?
2007-10-24 18:11:12,050 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (2))
2007-10-24 18:11:12,116 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,131 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,132 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (2))
2007-10-24 18:11:12,150 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,166 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,166 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (3))
2007-10-24 18:11:12,173 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,240 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,241 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (4))
2007-10-24 18:11:12,250 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,265 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,266 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (1)) and (centraltra1_.SEARCH_TYPE_ID in (1))
2007-10-24 18:11:12,267 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Done processing TPFM transactions. The max number of in process transactions is 156 and there are currently 1 transactions in process.
2007-10-24 18:11:12,268 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) End: XidImpl[FormatId=257, GlobalId=csnwas22/1621, BranchQual=2, localId=1621]
2007-10-24 18:11:12,269 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Ended XA transaction: XID:257:63736e77617332322f31363231:32
2007-10-24 18:11:12,269 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Prepare: XidImpl[FormatId=257, GlobalId=csnwas22/1621, BranchQual=2, localId=1621]
2007-10-24 18:11:12,343 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Commit: XidImpl[FormatId=257, GlobalId=csnwas22/1621, BranchQual=2, localId=1621]
2007-10-24 18:11:12,351 DEBUG [net.sf.ehcache.store.MemoryStore] (Thread-145) com.brn.moneycard.common.CentralTransactionCache: Cannot remove entry as key com.brn.moneycard.common.CentralTransaction#28980 was not found
2007-10-24 18:11:12,351 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) End: XidImpl[FormatId=257, GlobalId=csnwas22/1620, BranchQual=1, localId=1620]
2007-10-24 18:11:12,353 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Ended XA transaction: XID:257:63736e77617332322f31363230:31
2007-10-24 18:11:12,353 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) End: XidImpl[FormatId=257, GlobalId=csnwas22/1620, BranchQual=1, localId=1620]
2007-10-24 18:11:12,353 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Commit: XidImpl[FormatId=257, GlobalId=csnwas22/1620, BranchQual=1, localId=1620]
2007-10-24 18:11:12,374 DEBUG [org.apache.activemq.ra.ServerSessionImpl:28] (Thread-145) run loop end
2007-10-24 18:11:12,374 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl] (Thread-145) Session returned to pool: ServerSessionImpl:28
2007-10-24 18:11:12,374 DEBUG [org.apache.activemq.ra.ServerSessionImpl:28] (Thread-145) Run finished
2007-10-24 18:11:12,374 DEBUG [org.apache.activemq.ra.ServerSessionImpl:28] (Thread-145) Work completed: javax.resource.spi.work.WorkEvent[source=Work :ServerSessionImpl:28]
2007-10-24 18:11:12,374 DEBUG [org.apache.activemq.ra.ServerSessionImpl:19] (Thread-145) Work started: javax.resource.spi.work.WorkEvent[source=Work :ServerSessionImpl:19]
2007-10-24 18:11:12,374 DEBUG [org.apache.activemq.ra.ServerSessionImpl:19] (Thread-145) Running
2007-10-24 18:11:12,374 DEBUG [org.apache.activemq.ra.ServerSessionImpl:19] (Thread-145) run loop start
2007-10-24 18:11:12,374 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Start: XidImpl[FormatId=257, GlobalId=csnwas22/1632, BranchQual=1, localId=1632]
2007-10-24 18:11:12,374 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Started XA transaction: XID:257:63736e77617332322f31363332:31
2007-10-24 18:11:12,375 INFO [STDOUT] (Thread-145) Hibernate: select centraltra0_.ID as ID308_0_, centraltra0_.ALGORITHM_TYPE_ID as ALGORITHM2_308_0_, centraltra0_.START_DATE as START3_308_0_, centraltra0_.END_DATE as END4_308_0_, centraltra0_.DATABASE_TYPE_ID as DATABASE5_308_0_, centraltra0_.PRIORITY as PRIORITY308_0_, centraltra0_.LAST_MODIFIED_DATE as LAST7_308_0_, centraltra0_.PROCESSING_TYPE_ID as PROCESSING8_308_0_, centraltra0_.SEARCH_TYPE_ID as SEARCH9_308_0_, centraltra0_.STATUS as STATUS308_0_, centraltra0_.FILE_LOCATION as FILE11_308_0_, centraltra0_.NSN as NSN308_0_, centraltra0_.TCN as TCN308_0_, centraltra0_.REQUEST_XML as REQUEST14_308_0_, centraltra0_.RESPONSE_XML as RESPONSE15_308_0_, centraltra0_.LANTERN_INFO_ID as LANTERN16_308_0_, centraltra0_.LIVESCAN_INFO_ID as LIVESCAN17_308_0_, centraltra0_.BUREAU_SEARCH_INFO_ID as BUREAU18_308_0_, centraltra0_.BASTION_TRANSACTION_ID as BASTION19_308_0_, centraltra0_.MSS_RETRIES as MSS20_308_0_, centraltra0_.RESPONSE_RETRIES as RESPONSE21_308_0_, centraltra0_.ROLLED_UP as ROLLED22_308_0_ from CENTRAL_TRANSACTIONS centraltra0_ where centraltra0_.ID=?
2007-10-24 18:11:12,378 INFO [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowControllerMessageHandler] (Thread-145) NSN: 250710032451; Received notification that this transaction has changed processing status. Running scheduler logic.
2007-10-24 18:11:12,378 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found 2 transactions in the tpfmSearchRequest queue.
2007-10-24 18:11:12,378 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,395 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,396 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (8))
2007-10-24 18:11:12,406 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,425 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,426 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (6))
2007-10-24 18:11:12,448 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,465 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,465 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (1))
2007-10-24 18:11:12,476 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,493 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,493 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (2))
2007-10-24 18:11:12,521 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,540 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,541 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (3))
2007-10-24 18:11:12,554 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,573 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,573 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (4))
2007-10-24 18:11:12,584 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,605 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,606 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (1)) and (centraltra1_.SEARCH_TYPE_ID in (1))
2007-10-24 18:11:12,609 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) No transactions found during pass. Resetting the limits for all bureaus and trying again.
2007-10-24 18:11:12,609 INFO [STDOUT] (Thread-145) Hibernate: select bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_
2007-10-24 18:11:12,614 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,666 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,668 INFO [STDOUT] (Thread-145) Hibernate: update BUREAUS set CODE=?, PRIME=?, ROUND_ROBIN_COUNT=?, BUREAU_ID=?, ROUND_ROBIN_WEIGHT=? where ID=?
2007-10-24 18:11:12,672 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (8))
2007-10-24 18:11:12,681 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,698 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,699 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (6))
2007-10-24 18:11:12,707 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,745 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,747 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (1))
2007-10-24 18:11:12,761 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,781 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,782 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (2))
2007-10-24 18:11:12,817 INFO [STDOUT] (Thread-145) Hibernate: select centraltra0_.ID as col_0_0_ from CENTRAL_TRANSACTIONS centraltra0_, LIVESCAN_INFO livescanin1_ where centraltra0_.LIVESCAN_INFO_ID=livescanin1_.ID and centraltra0_.STATUS=? and livescanin1_.PRIORITY=? and livescanin1_.BUREAU_ID=? and (centraltra0_.PROCESSING_TYPE_ID in (4)) and (centraltra0_.SEARCH_TYPE_ID in (2)) and (centraltra0_.DATABASE_TYPE_ID in (1 , 2 , 3 , 4 , 5 , 6 , 7 , 8)) order by centraltra0_.START_DATE
2007-10-24 18:11:12,875 INFO [STDOUT] (Thread-145) Hibernate: select centraltra0_.ID as ID308_0_, centraltra0_.ALGORITHM_TYPE_ID as ALGORITHM2_308_0_, centraltra0_.START_DATE as START3_308_0_, centraltra0_.END_DATE as END4_308_0_, centraltra0_.DATABASE_TYPE_ID as DATABASE5_308_0_, centraltra0_.PRIORITY as PRIORITY308_0_, centraltra0_.LAST_MODIFIED_DATE as LAST7_308_0_, centraltra0_.PROCESSING_TYPE_ID as PROCESSING8_308_0_, centraltra0_.SEARCH_TYPE_ID as SEARCH9_308_0_, centraltra0_.STATUS as STATUS308_0_, centraltra0_.FILE_LOCATION as FILE11_308_0_, centraltra0_.NSN as NSN308_0_, centraltra0_.TCN as TCN308_0_, centraltra0_.REQUEST_XML as REQUEST14_308_0_, centraltra0_.RESPONSE_XML as RESPONSE15_308_0_, centraltra0_.LANTERN_INFO_ID as LANTERN16_308_0_, centraltra0_.LIVESCAN_INFO_ID as LIVESCAN17_308_0_, centraltra0_.BUREAU_SEARCH_INFO_ID as BUREAU18_308_0_, centraltra0_.BASTION_TRANSACTION_ID as BASTION19_308_0_, centraltra0_.MSS_RETRIES as MSS20_308_0_, centraltra0_.RESPONSE_RETRIES as RESPONSE21_308_0_, centraltra0_.ROLLED_UP as ROLLED22_308_0_ from CENTRAL_TRANSACTIONS centraltra0_ where centraltra0_.ID=?
2007-10-24 18:11:12,879 INFO [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) NSN: 250710028980; The flow controller found that this transaction is the next in line for processing.
2007-10-24 18:11:12,879 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) There are currently 2 transactions queued for TPFM. A maximum of 156 transactions can be in the Queue.
2007-10-24 18:11:12,880 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Start: XidImpl[FormatId=257, GlobalId=csnwas22/1633, BranchQual=2, localId=1633]
2007-10-24 18:11:12,880 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Started XA transaction: XID:257:63736e77617332322f31363333:32
2007-10-24 18:11:12,881 DEBUG [org.apache.activemq.ActiveMQSession] (Thread-145) Sending message: ActiveMQObjectMessage {commandId = 0, responseRequired = false, messageId = ID:csnwas22-44347-1193249272745-4:137:1:1:166, originalDestination = null, originalTransactionId = null, producerId = ID:csnwas22-44347-1193249272745-4:137:1:1, destination = queue://tpfmSearchRequestQueue, transactionId = XID:257:63736e77617332322f31363333:32, expiration = 0, timestamp = 1193249472881, arrival = 0, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@13ac3ee, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false}
2007-10-24 18:11:12,882 INFO [STDOUT] (Thread-145) Sending 28981
2007-10-24 18:11:12,882 INFO [STDOUT] (Thread-145) Hibernate: select count(IMAGE_DATA_ID) from TRANSACTIONS_IMAGE_DATA where TRANSACTION_ID =?
2007-10-24 18:11:12,886 INFO [STDOUT] (Thread-145) Hibernate: select count(MINUTIAE_DATA_ID) from TRANSACTIONS_MINUTIAE_DATA where TRANSACTION_ID =?
2007-10-24 18:11:12,887 INFO [STDOUT] (Thread-145) Hibernate: update CENTRAL_TRANSACTIONS set ALGORITHM_TYPE_ID=?, END_DATE=?, DATABASE_TYPE_ID=?, PRIORITY=?, LAST_MODIFIED_DATE=?, PROCESSING_TYPE_ID=?, SEARCH_TYPE_ID=?, STATUS=?, FILE_LOCATION=?, NSN=?, TCN=?, REQUEST_XML=?, RESPONSE_XML=?, LANTERN_INFO_ID=?, LIVESCAN_INFO_ID=?, BUREAU_SEARCH_INFO_ID=?, BASTION_TRANSACTION_ID=?, MSS_RETRIES=?, RESPONSE_RETRIES=?, ROLLED_UP=? where ID=?
2007-10-24 18:11:12,893 INFO [STDOUT] (Thread-145) Hibernate: select livescanin0_.ID as ID318_0_, livescanin0_.BUREAU_ID as BUREAU2_318_0_, livescanin0_.EMAIL_ADDRESS as EMAIL3_318_0_, livescanin0_.PRIORITY as PRIORITY318_0_ from LIVESCAN_INFO livescanin0_ where livescanin0_.ID=?
2007-10-24 18:11:12,894 INFO [STDOUT] (Thread-145) Hibernate: select bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_ where bureau0_.BUREAU_ID=?
2007-10-24 18:11:12,895 INFO [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) NSN: 250710028980; Transaction was successfully sent to TPFM processing queue.
2007-10-24 18:11:12,896 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,912 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,912 INFO [STDOUT] (Thread-145) Hibernate: update BUREAUS set CODE=?, PRIME=?, ROUND_ROBIN_COUNT=?, BUREAU_ID=?, ROUND_ROBIN_WEIGHT=? where ID=?
2007-10-24 18:11:12,914 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (2))
2007-10-24 18:11:12,933 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,949 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:12,950 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (2))
2007-10-24 18:11:12,968 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:12,993 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,258 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (3))
2007-10-24 18:11:13,266 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,331 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,332 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (4))
2007-10-24 18:11:13,345 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,366 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,366 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (1)) and (centraltra1_.SEARCH_TYPE_ID in (1))
2007-10-24 18:11:13,369 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Done processing TPFM transactions. The max number of in process transactions is 156 and there are currently 2 transactions in process.
2007-10-24 18:11:13,370 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) End: XidImpl[FormatId=257, GlobalId=csnwas22/1633, BranchQual=2, localId=1633]
2007-10-24 18:11:13,370 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Ended XA transaction: XID:257:63736e77617332322f31363333:32
2007-10-24 18:11:13,370 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Prepare: XidImpl[FormatId=257, GlobalId=csnwas22/1633, BranchQual=2, localId=1633]
2007-10-24 18:11:13,631 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Commit: XidImpl[FormatId=257, GlobalId=csnwas22/1633, BranchQual=2, localId=1633]
2007-10-24 18:11:13,652 DEBUG [net.sf.ehcache.store.MemoryStore] (Thread-145) com.brn.moneycard.common.CentralTransactionCache: Cannot remove entry as key com.brn.moneycard.common.CentralTransaction#28981 was not found
2007-10-24 18:11:13,653 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) End: XidImpl[FormatId=257, GlobalId=csnwas22/1632, BranchQual=1, localId=1632]
2007-10-24 18:11:13,653 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Ended XA transaction: XID:257:63736e77617332322f31363332:31
2007-10-24 18:11:13,653 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) End: XidImpl[FormatId=257, GlobalId=csnwas22/1632, BranchQual=1, localId=1632]
2007-10-24 18:11:13,653 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Commit: XidImpl[FormatId=257, GlobalId=csnwas22/1632, BranchQual=1, localId=1632]
2007-10-24 18:11:13,659 DEBUG [org.apache.activemq.ra.ServerSessionImpl:19] (Thread-145) run loop end
2007-10-24 18:11:13,659 DEBUG [org.apache.activemq.ra.ServerSessionPoolImpl] (Thread-145) Session returned to pool: ServerSessionImpl:19
2007-10-24 18:11:13,659 DEBUG [org.apache.activemq.ra.ServerSessionImpl:19] (Thread-145) Run finished
2007-10-24 18:11:13,660 DEBUG [org.apache.activemq.ra.ServerSessionImpl:19] (Thread-145) Work completed: javax.resource.spi.work.WorkEvent[source=Work :ServerSessionImpl:19]
2007-10-24 18:11:13,660 DEBUG [org.apache.activemq.ra.ServerSessionImpl:15] (Thread-145) Work started: javax.resource.spi.work.WorkEvent[source=Work :ServerSessionImpl:15]
2007-10-24 18:11:13,660 DEBUG [org.apache.activemq.ra.ServerSessionImpl:15] (Thread-145) Running
2007-10-24 18:11:13,660 DEBUG [org.apache.activemq.ra.ServerSessionImpl:15] (Thread-145) run loop start
2007-10-24 18:11:13,660 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Start: XidImpl[FormatId=257, GlobalId=csnwas22/1642, BranchQual=1, localId=1642]
2007-10-24 18:11:13,660 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Started XA transaction: XID:257:63736e77617332322f31363432:31
2007-10-24 18:11:13,660 INFO [STDOUT] (Thread-145) Hibernate: select centraltra0_.ID as ID308_0_, centraltra0_.ALGORITHM_TYPE_ID as ALGORITHM2_308_0_, centraltra0_.START_DATE as START3_308_0_, centraltra0_.END_DATE as END4_308_0_, centraltra0_.DATABASE_TYPE_ID as DATABASE5_308_0_, centraltra0_.PRIORITY as PRIORITY308_0_, centraltra0_.LAST_MODIFIED_DATE as LAST7_308_0_, centraltra0_.PROCESSING_TYPE_ID as PROCESSING8_308_0_, centraltra0_.SEARCH_TYPE_ID as SEARCH9_308_0_, centraltra0_.STATUS as STATUS308_0_, centraltra0_.FILE_LOCATION as FILE11_308_0_, centraltra0_.NSN as NSN308_0_, centraltra0_.TCN as TCN308_0_, centraltra0_.REQUEST_XML as REQUEST14_308_0_, centraltra0_.RESPONSE_XML as RESPONSE15_308_0_, centraltra0_.LANTERN_INFO_ID as LANTERN16_308_0_, centraltra0_.LIVESCAN_INFO_ID as LIVESCAN17_308_0_, centraltra0_.BUREAU_SEARCH_INFO_ID as BUREAU18_308_0_, centraltra0_.BASTION_TRANSACTION_ID as BASTION19_308_0_, centraltra0_.MSS_RETRIES as MSS20_308_0_, centraltra0_.RESPONSE_RETRIES as RESPONSE21_308_0_, centraltra0_.ROLLED_UP as ROLLED22_308_0_ from CENTRAL_TRANSACTIONS centraltra0_ where centraltra0_.ID=?
2007-10-24 18:11:13,663 INFO [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowControllerMessageHandler] (Thread-145) NSN: 250710032452; Received notification that this transaction has changed processing status. Running scheduler logic.
2007-10-24 18:11:13,664 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found 1 transactions in the tpfmSearchRequest queue.
2007-10-24 18:11:13,664 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,681 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,681 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (8))
2007-10-24 18:11:13,689 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,714 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,714 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (6))
2007-10-24 18:11:13,722 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,740 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,741 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (1))
2007-10-24 18:11:13,752 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,771 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,771 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (2))
2007-10-24 18:11:13,791 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,809 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,809 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (3))
2007-10-24 18:11:13,817 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,856 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,857 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (4))
2007-10-24 18:11:13,867 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,885 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,908 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (1)) and (centraltra1_.SEARCH_TYPE_ID in (1))
2007-10-24 18:11:13,913 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) No transactions found during pass. Resetting the limits for all bureaus and trying again.
2007-10-24 18:11:13,914 INFO [STDOUT] (Thread-145) Hibernate: select bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_
2007-10-24 18:11:13,920 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:13,937 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:13,938 INFO [STDOUT] (Thread-145) Hibernate: update BUREAUS set CODE=?, PRIME=?, ROUND_ROBIN_COUNT=?, BUREAU_ID=?, ROUND_ROBIN_WEIGHT=? where ID=?
2007-10-24 18:11:13,956 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (8))
2007-10-24 18:11:13,964 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:14,011 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:14,013 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (6))
2007-10-24 18:11:14,022 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:14,039 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:14,040 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (1))
2007-10-24 18:11:14,054 INFO [STDOUT] (Thread-145) Hibernate: select distinct livescanin0_.PRIORITY as col_0_0_ from LIVESCAN_INFO livescanin0_, CENTRAL_TRANSACTIONS centraltra1_ where livescanin0_.ID=centraltra1_.LIVESCAN_INFO_ID and centraltra1_.STATUS=2 order by livescanin0_.PRIORITY desc
2007-10-24 18:11:14,071 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) Found the following priorities used by scheduled transactions: 1
2007-10-24 18:11:14,072 INFO [STDOUT] (Thread-145) Hibernate: select distinct bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_, CENTRAL_TRANSACTIONS centraltra1_, LIVESCAN_INFO livescanin2_ where centraltra1_.LIVESCAN_INFO_ID=livescanin2_.ID and livescanin2_.PRIORITY=1 and livescanin2_.BUREAU_ID=bureau0_.BUREAU_ID and (centraltra1_.STATUS='2' or centraltra1_.STATUS='4') and bureau0_.ROUND_ROBIN_COUNT<bureau0_.ROUND_ROBIN_WEIGHT and (centraltra1_.PROCESSING_TYPE_ID in (4)) and (centraltra1_.SEARCH_TYPE_ID in (2))
2007-10-24 18:11:14,126 INFO [STDOUT] (Thread-145) Hibernate: select centraltra0_.ID as col_0_0_ from CENTRAL_TRANSACTIONS centraltra0_, LIVESCAN_INFO livescanin1_ where centraltra0_.LIVESCAN_INFO_ID=livescanin1_.ID and centraltra0_.STATUS=? and livescanin1_.PRIORITY=? and livescanin1_.BUREAU_ID=? and (centraltra0_.PROCESSING_TYPE_ID in (4)) and (centraltra0_.SEARCH_TYPE_ID in (2)) and (centraltra0_.DATABASE_TYPE_ID in (1 , 2 , 3 , 4 , 5 , 6 , 7 , 8)) order by centraltra0_.START_DATE
2007-10-24 18:11:14,166 INFO [STDOUT] (Thread-145) Hibernate: select centraltra0_.ID as ID308_0_, centraltra0_.ALGORITHM_TYPE_ID as ALGORITHM2_308_0_, centraltra0_.START_DATE as START3_308_0_, centraltra0_.END_DATE as END4_308_0_, centraltra0_.DATABASE_TYPE_ID as DATABASE5_308_0_, centraltra0_.PRIORITY as PRIORITY308_0_, centraltra0_.LAST_MODIFIED_DATE as LAST7_308_0_, centraltra0_.PROCESSING_TYPE_ID as PROCESSING8_308_0_, centraltra0_.SEARCH_TYPE_ID as SEARCH9_308_0_, centraltra0_.STATUS as STATUS308_0_, centraltra0_.FILE_LOCATION as FILE11_308_0_, centraltra0_.NSN as NSN308_0_, centraltra0_.TCN as TCN308_0_, centraltra0_.REQUEST_XML as REQUEST14_308_0_, centraltra0_.RESPONSE_XML as RESPONSE15_308_0_, centraltra0_.LANTERN_INFO_ID as LANTERN16_308_0_, centraltra0_.LIVESCAN_INFO_ID as LIVESCAN17_308_0_, centraltra0_.BUREAU_SEARCH_INFO_ID as BUREAU18_308_0_, centraltra0_.BASTION_TRANSACTION_ID as BASTION19_308_0_, centraltra0_.MSS_RETRIES as MSS20_308_0_, centraltra0_.RESPONSE_RETRIES as RESPONSE21_308_0_, centraltra0_.ROLLED_UP as ROLLED22_308_0_ from CENTRAL_TRANSACTIONS centraltra0_ where centraltra0_.ID=?
2007-10-24 18:11:14,169 INFO [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) NSN: 250710028980; The flow controller found that this transaction is the next in line for processing.
2007-10-24 18:11:14,169 DEBUG [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowController] (Thread-145) There are currently 1 transactions queued for TPFM. A maximum of 156 transactions can be in the Queue.
2007-10-24 18:11:14,169 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Start: XidImpl[FormatId=257, GlobalId=csnwas22/1643, BranchQual=2, localId=1643]
2007-10-24 18:11:14,170 DEBUG [org.apache.activemq.TransactionContext] (Thread-145) Started XA transaction: XID:257:63736e77617332322f31363433:32
2007-10-24 18:11:14,170 DEBUG [org.apache.activemq.ActiveMQSession] (Thread-145) Sending message: ActiveMQObjectMessage {commandId = 0, responseRequired = false, messageId = ID:csnwas22-44347-1193249272745-4:137:1:1:167, originalDestination = null, originalTransactionId = null, producerId = ID:csnwas22-44347-1193249272745-4:137:1:1, destination = queue://tpfmSearchRequestQueue, transactionId = XID:257:63736e77617332322f31363433:32, expiration = 0, timestamp = 1193249474170, arrival = 0, correlationId = null, replyTo = null, persistent = true, type = null, priority = 4, groupID = null, groupSequence = 0, targetConsumerId = null, compressed = false, userID = null, content = org.apache.activemq.util.ByteSequence@c289ac, marshalledProperties = null, dataStructure = null, redeliveryCounter = 0, size = 0, properties = null, readOnlyProperties = true, readOnlyBody = true, droppable = false}
2007-10-24 18:11:14,170 INFO [STDOUT] (Thread-145) Sending 28981
2007-10-24 18:11:14,171 INFO [STDOUT] (Thread-145) Hibernate: select count(IMAGE_DATA_ID) from TRANSACTIONS_IMAGE_DATA where TRANSACTION_ID =?
2007-10-24 18:11:14,175 INFO [STDOUT] (Thread-145) Hibernate: select count(MINUTIAE_DATA_ID) from TRANSACTIONS_MINUTIAE_DATA where TRANSACTION_ID =?
2007-10-24 18:11:14,176 INFO [STDOUT] (Thread-145) Hibernate: update CENTRAL_TRANSACTIONS set ALGORITHM_TYPE_ID=?, END_DATE=?, DATABASE_TYPE_ID=?, PRIORITY=?, LAST_MODIFIED_DATE=?, PROCESSING_TYPE_ID=?, SEARCH_TYPE_ID=?, STATUS=?, FILE_LOCATION=?, NSN=?, TCN=?, REQUEST_XML=?, RESPONSE_XML=?, LANTERN_INFO_ID=?, LIVESCAN_INFO_ID=?, BUREAU_SEARCH_INFO_ID=?, BASTION_TRANSACTION_ID=?, MSS_RETRIES=?, RESPONSE_RETRIES=?, ROLLED_UP=? where ID=?
2007-10-24 18:11:14,176 DEBUG [net.sf.ehcache.store.MemoryStore] (Thread-145) com.brn.moneycard.common.CentralTransactionCache: Cannot remove entry as key com.brn.moneycard.common.CentralTransaction#28981 was not found
2007-10-24 18:11:14,182 INFO [STDOUT] (Thread-145) Hibernate: select livescanin0_.ID as ID318_0_, livescanin0_.BUREAU_ID as BUREAU2_318_0_, livescanin0_.EMAIL_ADDRESS as EMAIL3_318_0_, livescanin0_.PRIORITY as PRIORITY318_0_ from LIVESCAN_INFO livescanin0_ where livescanin0_.ID=?
2007-10-24 18:11:14,183 INFO [STDOUT] (Thread-145) Hibernate: select bureau0_.ID as ID306_, bureau0_.CODE as CODE306_, bureau0_.PRIME as PRIME306_, bureau0_.ROUND_ROBIN_COUNT as ROUND4_306_, bureau0_.BUREAU_ID as BUREAU5_306_, bureau0_.ROUND_ROBIN_WEIGHT as ROUND6_306_ from BUREAUS bureau0_ where bureau0_.BUREAU_ID=?
2007-10-24 18:11:14,184 INFO [com.brn.moneycard.flow.bureauweighted.BureauWeightedFlowCo

Top

Page 1 of 1

[ 1 post ]

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