7 Replies Latest reply on Feb 15, 2008 4:18 PM by uryl

    Transaction is not timing out

      Hi guys,

      My environment is JBoss 4.2.0, JDK 1.5.0_12-b04, Microsoft SQL Server 2005. I have a Stateless Session Bean "StatHoliday". In it I have 3 EJB methods getPaymentCalc, calculateStatHolidayPay [2 arguments] and calculateStatHolidayPay [8 arguments]. The first 2 method's transaction attribute is NotSupported while third one's transaction attribute is RequiresNew.

       <container-transaction>
       <method>
       <ejb-name>StatHoliday</ejb-name>
       <method-intf>Remote</method-intf>
       <method-name>getPaymentCalc</method-name>
       <method-params>
       <method-param>java.util.Map</method-param>
       <method-param>java.lang.String</method-param>
       <method-param>java.lang.String[]</method-param>
       <method-param>java.lang.String</method-param>
       <method-param>java.lang.String[]</method-param>
       <method-param>java.lang.String</method-param>
       <method-param>java.lang.String[]</method-param>
       <method-param>java.lang.String</method-param>
       <method-param>java.lang.String[]</method-param>
       <method-param>java.lang.String</method-param>
       <method-param>java.lang.String[]</method-param>
       <method-param>java.lang.String</method-param>
       <method-param>java.lang.String[]</method-param>
       <method-param>java.lang.String</method-param>
       <method-param>java.lang.String[]</method-param>
       <method-param>java.lang.String</method-param>
       <method-param>java.lang.String[]</method-param>
       <method-param>java.lang.String</method-param>
       </method-params>
       </method>
       <trans-attribute>NotSupported</trans-attribute>
       </container-transaction>
       <container-transaction>
       <method>
       <ejb-name>StatHoliday</ejb-name>
       <method-intf>Remote</method-intf>
       <method-name>calculateStatHolidayPay</method-name>
       <method-params>
       <method-param>java.util.Map</method-param>
       <method-param>int[]</method-param>
       </method-params>
       </method>
       <trans-attribute>NotSupported</trans-attribute>
       </container-transaction>
       <container-transaction>
       <method>
       <ejb-name>StatHoliday</ejb-name>
       <method-intf>Remote</method-intf>
       <method-name>calculateStatHolidayPay</method-name>
       <method-params>
       <method-param>com.pendylum.base.connection.ConnectionVO</method-param>
       <method-param>java.util.Map</method-param>
       <method-param>short[]</method-param>
       <method-param>short[]</method-param>
       <method-param>short[]</method-param>
       <method-param>short[]</method-param>
       <method-param>java.util.Map</method-param>
       <method-param>int</method-param>
       </method-params>
       </method>
       <trans-attribute>RequiresNew</trans-attribute>
       </container-transaction>
      


      User selects one or more Stat Holiday to process in JSP. The JSP then calls the getPaymentCalc EJB method which calls calculateStatHolidayPay [2 arguments] method which calls calculateStatHolidayPay [8 arguments] method for each Stat Holiday.

      A Stat Holiday takes 4+ minutes to process. I set the transaction timeout to 1 minute in conf/jboss-ervice.xml
       <!-- JBoss Transactions JTA -->
       <mbean code="com.arjuna.ats.jbossatx.jta.TransactionManagerService"
       name="jboss:service=TransactionManager">
       <attribute name="TransactionTimeout">60</attribute>
       <attribute name="ObjectStoreDir">${jboss.server.data.dir}/tx-object-store</attribute>
       </mbean>
      


      I run the process for one Stat Holiday and it took 3 minutes to complete. But the transaction did not timed out and i can see all the data it inserted in a table is there.

      This is the log file:

      2008-02-15 10:52:45,095 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): --- START
      2008-02-15 10:52:45,126 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): StatHolidayDate= StatHolidayDate [Id=1; HolidayId=1; ProvinceId=1; LocationId=null; HolidayDate=2006-09-07; HolidayYear=2006]
      2008-02-15 10:52:45,345 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): StatHolidayEligibility= StatHolidayEligibility [Id=1; ProvinceId=1; HolidayId=1; DaysEmployed=30; DaysEmployedTypeId=1; DaysRequired=28; DaysRequiredTypeId=1; EligDaysPreceding=60; EligDaysPrecedingTypeId=1; CalcDaysPreceding=4; CalcDaysPrecedingTypeId=2; AverageMethodId=2; DateReferenceId=1; TerminationRuleFlag=false; Detail's Size=1]
      2008-02-15 10:52:45,361 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): PayFrequencies filtered= 2
      2008-02-15 10:52:45,392 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Locations filtered= 1,2,3,4,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,24,25,29,30,31,32,33,34,35,36,37,38
      2008-02-15 10:52:45,423 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ---------> StatHolidayEligibilityDetails[0]= StatHolidayEligibilityDetail [Id=9; EligibilityId=1; CriteriaId=1; HolidayRuleId=1; HoursRuleId=1; RateRuleId=1; RegularTimeFlag=true; OvertimeFlag=false; DoubleTimeFlag=false; ESCFlag=true]
      2008-02-15 10:52:45,423 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ------> StatHolidayCriteriaDetails[0]= StatHolidayCriteriaDetail [Id=172; StatHolidayCriteria=StatHolidayCriteria [Id=1; Name=HRLY_FT; Detail's Size=4]; JobClassId=1; PayTypeId=1; UnionId=0]
      2008-02-15 10:52:49,736 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Employees to process= 1274
      2008-02-15 10:53:22,486 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 10:53:22>
      2008-02-15 10:53:22,486 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
      2008-02-15 10:53:22,486 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
      2008-02-15 10:53:22,486 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
      2008-02-15 10:53:32,486 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 10:53:32>
      2008-02-15 10:53:32,486 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
      2008-02-15 10:53:32,486 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
      2008-02-15 10:53:32,486 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
      2008-02-15 10:55:32,486 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 10:55:32>
      2008-02-15 10:55:32,486 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
      2008-02-15 10:55:32,486 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
      2008-02-15 10:55:32,486 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
      2008-02-15 10:55:41,282 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ------> StatHolidayCriteriaDetails[1]= StatHolidayCriteriaDetail [Id=173; StatHolidayCriteria=StatHolidayCriteria [Id=1; Name=HRLY_FT; Detail's Size=4]; JobClassId=4; PayTypeId=1; UnionId=0]
      2008-02-15 10:55:41,298 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Employees to process= 0
      2008-02-15 10:55:41,298 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ------> StatHolidayCriteriaDetails[2]= StatHolidayCriteriaDetail [Id=174; StatHolidayCriteria=StatHolidayCriteria [Id=1; Name=HRLY_FT; Detail's Size=4]; JobClassId=1; PayTypeId=1; UnionId=1]
      2008-02-15 10:55:41,314 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Employees to process= 0
      2008-02-15 10:55:41,314 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ------> StatHolidayCriteriaDetails[3]= StatHolidayCriteriaDetail [Id=175; StatHolidayCriteria=StatHolidayCriteria [Id=1; Name=HRLY_FT; Detail's Size=4]; JobClassId=4; PayTypeId=1; UnionId=1]
      2008-02-15 10:55:41,314 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Employees to process= 0
      2008-02-15 10:55:42,486 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 10:55:42>
      2008-02-15 10:55:42,486 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
      2008-02-15 10:55:42,486 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
      2008-02-15 10:55:42,486 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
      2008-02-15 10:55:43,548 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): --- END



      Can somebody please explain why the transaction did not timed out?

      Thanks

        • 1. Re: Transaction is not timing out

          In the boot.log file I can see that JBoss is using 60 seconds for transaction timeout:


          10:50:00,767 DEBUG [ServiceCreator] Created bean: jboss:service=TransactionManager
          10:50:00,767 DEBUG [ServiceConfigurator] TransactionTimeout set to 60 in jboss:service=TransactionManager



          • 2. Re: Transaction is not timing out
            jhalliday

            what transaction reaper configuration are you using? depending on the mode, transaction timeout processing may happen periodically rather than immediately. In either case it's asynchronous, so the application won't necessarily notice until commit time.

            • 3. Re: Transaction is not timing out

               

              "jhalliday" wrote:
              what transaction reaper configuration are you using? depending on the mode, transaction timeout processing may happen periodically rather than immediately. In either case it's asynchronous, so the application won't necessarily notice until commit time.


              I dont know about transaction reaper configuration. Can you tell me what it is and how to check it? Thanks


              • 4. Re: Transaction is not timing out

                ok i got the transaction reaper configuration in conf/jbossjta-properties.xml as:

                <!-- Transaction Reaper Timeout (default is 120000 microseconds). -->
                <property name="com.arjuna.ats.arjuna.coordinator.txReaperTimeout" value="120000"/>
                
                <!-- Transaction Reaper Mode, can be: NORMAL or DYNAMIC (default is NORMAL). -->
                <property name="com.arjuna.ats.arjuna.coordinator.txReaperMode" value="NORMAL"/>


                • 5. Re: Transaction is not timing out
                  adinn

                  The reaper repeatedly sleeps then wakes up and checks for timed out active transactions.

                  In normal mode it wakes up after a fixed interval So,with your config your transaction may time out after 4 seconds but it could take up 120 seconds for it to get cancelled.

                  In dynamic mode the reaper calculates a wakeup time just after the earliest timeout of any active transaction. So, with this mode when your transaction times out the reaper should wake up shortly after and kill it.

                  • 6. Re: Transaction is not timing out

                    ok so i changed Transaction Reaper Mode to DYNAMIC in conf/jbossjta-properties.xml :

                    <!-- Transaction Reaper Mode, can be: NORMAL or DYNAMIC (default is NORMAL). -->
                    <property name="com.arjuna.ats.arjuna.coordinator.txReaperMode" value="DYNAMIC"/>


                    Still I got no timeout or abort message in server.log and the data inserted in table remains there. This is the server.log

                    2008-02-15 15:17:09,717 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): --- START
                    2008-02-15 15:17:09,764 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): StatHolidayDate= StatHolidayDate [Id=1; HolidayId=1; ProvinceId=1; LocationId=null; HolidayDate=2006-09-07; HolidayYear=2006]
                    2008-02-15 15:17:10,343 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): StatHolidayEligibility= StatHolidayEligibility [Id=1; ProvinceId=1; HolidayId=1; DaysEmployed=30; DaysEmployedTypeId=1; DaysRequired=28; DaysRequiredTypeId=1; EligDaysPreceding=60; EligDaysPrecedingTypeId=1; CalcDaysPreceding=4; CalcDaysPrecedingTypeId=2; AverageMethodId=2; DateReferenceId=1; TerminationRuleFlag=false; Detail's Size=1]
                    2008-02-15 15:17:10,343 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): PayFrequencies filtered= 2
                    2008-02-15 15:17:10,374 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Locations filtered= 1,2,3,4,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,24,25,29,30,31,32,33,34,35,36,37,38
                    2008-02-15 15:17:10,421 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ---------> StatHolidayEligibilityDetails[0]= StatHolidayEligibilityDetail [Id=9; EligibilityId=1; CriteriaId=1; HolidayRuleId=1; HoursRuleId=1; RateRuleId=1; RegularTimeFlag=true; OvertimeFlag=false; DoubleTimeFlag=false; ESCFlag=true]
                    2008-02-15 15:17:10,421 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ------> StatHolidayCriteriaDetails[0]= StatHolidayCriteriaDetail [Id=172; StatHolidayCriteria=StatHolidayCriteria [Id=1; Name=HRLY_FT; Detail's Size=4]; JobClassId=1; PayTypeId=1; UnionId=0]
                    2008-02-15 15:17:10,796 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:17:10>
                    2008-02-15 15:17:10,796 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                    2008-02-15 15:17:10,796 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                    2008-02-15 15:17:11,468 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                    2008-02-15 15:17:14,532 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Employees to process= 1274
                    2008-02-15 15:17:21,472 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:17:21>
                    2008-02-15 15:17:21,472 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                    2008-02-15 15:17:21,472 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                    2008-02-15 15:17:21,472 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                    2008-02-15 15:19:21,496 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:19:21>
                    2008-02-15 15:19:21,496 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                    2008-02-15 15:19:21,496 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                    2008-02-15 15:19:21,496 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                    2008-02-15 15:19:31,497 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:19:31>
                    2008-02-15 15:19:31,497 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                    2008-02-15 15:19:31,497 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                    2008-02-15 15:19:31,497 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                    2008-02-15 15:20:03,079 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ------> StatHolidayCriteriaDetails[1]= StatHolidayCriteriaDetail [Id=173; StatHolidayCriteria=StatHolidayCriteria [Id=1; Name=HRLY_FT; Detail's Size=4]; JobClassId=4; PayTypeId=1; UnionId=0]
                    2008-02-15 15:20:03,157 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Employees to process= 0
                    2008-02-15 15:20:03,157 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ------> StatHolidayCriteriaDetails[2]= StatHolidayCriteriaDetail [Id=174; StatHolidayCriteria=StatHolidayCriteria [Id=1; Name=HRLY_FT; Detail's Size=4]; JobClassId=1; PayTypeId=1; UnionId=1]
                    2008-02-15 15:20:03,157 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Employees to process= 0
                    2008-02-15 15:20:03,157 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): ------> StatHolidayCriteriaDetails[3]= StatHolidayCriteriaDetail [Id=175; StatHolidayCriteria=StatHolidayCriteria [Id=1; Name=HRLY_FT; Detail's Size=4]; JobClassId=4; PayTypeId=1; UnionId=1]
                    2008-02-15 15:20:03,173 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): Employees to process= 0
                    2008-02-15 15:20:05,079 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): --- END


                    Do I have to change the Transaction Reaper Timeout as well although I beleive it should not have any effect if Transacation Reaper Mode is "DYNAMIC".



                    • 7. Re: Transaction is not timing out

                      I keep my JBoss open for one more hour hoping that maybe the timeout will kick in but did not happend. In the serever.log file though it prints some more info:


                      2008-02-15 15:20:05,079 INFO [com.pendylum.ejb.StatHolidayBean] calculateStatHolidayPay(): --- END
                      2008-02-15 15:21:31,510 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:21:31>
                      2008-02-15 15:21:31,510 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:21:31,510 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:21:31,510 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:21:41,511 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:21:41>
                      2008-02-15 15:21:41,511 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:21:41,511 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:21:41,511 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:22:29,656 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
                      2008-02-15 15:23:41,520 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:23:41>
                      2008-02-15 15:23:41,520 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:23:41,520 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:23:41,520 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:23:51,536 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:23:51>
                      2008-02-15 15:23:51,536 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:23:51,536 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:23:51,536 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:25:51,553 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:25:51>
                      2008-02-15 15:25:51,553 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:25:51,553 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:25:51,553 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:26:01,553 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:26:01>
                      2008-02-15 15:26:01,553 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:26:01,553 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:26:01,553 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:28:01,555 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:28:01>
                      2008-02-15 15:28:01,555 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:28:01,555 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:28:01,555 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:28:11,555 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:28:11>
                      2008-02-15 15:28:11,555 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:28:11,555 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:28:11,555 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:29:59,662 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
                      2008-02-15 15:30:11,554 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:30:11>
                      2008-02-15 15:30:11,554 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:30:11,554 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:30:11,554 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:30:21,556 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:30:21>
                      2008-02-15 15:30:21,556 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:30:21,556 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:30:21,556 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:32:21,570 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:32:21>
                      2008-02-15 15:32:21,570 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:32:21,570 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:32:21,570 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:32:31,571 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:32:31>
                      2008-02-15 15:32:31,571 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:32:31,571 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:32:31,571 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:34:31,586 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:34:31>
                      2008-02-15 15:34:31,586 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:34:31,586 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:34:31,586 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:34:41,587 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:34:41>
                      2008-02-15 15:34:41,587 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:34:41,587 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:34:41,587 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:36:41,594 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:36:41>
                      2008-02-15 15:36:41,594 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:36:41,594 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:36:41,594 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:36:51,594 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:36:51>
                      2008-02-15 15:36:51,594 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:36:51,594 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:36:51,594 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:37:29,706 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
                      2008-02-15 15:38:51,600 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:38:51>
                      2008-02-15 15:38:51,600 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:38:51,600 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:38:51,600 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:39:01,600 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:39:01>
                      2008-02-15 15:39:01,600 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:39:01,600 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:39:01,600 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:41:01,604 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:41:01>
                      2008-02-15 15:41:01,604 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:41:01,604 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:41:01,604 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:41:11,604 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:41:11>
                      2008-02-15 15:41:11,604 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:41:11,604 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:41:11,604 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:43:11,607 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:43:11>
                      2008-02-15 15:43:11,607 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:43:11,607 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:43:11,607 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:43:21,607 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:43:21>
                      2008-02-15 15:43:21,607 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:43:21,607 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:43:21,607 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:44:59,749 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
                      2008-02-15 15:45:21,609 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:45:21>
                      2008-02-15 15:45:21,609 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:45:21,609 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:45:21,609 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:45:31,609 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:45:31>
                      2008-02-15 15:45:31,609 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:45:31,609 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:45:31,609 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:47:31,610 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:47:31>
                      2008-02-15 15:47:31,610 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:47:31,610 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:47:31,610 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:47:41,610 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:47:41>
                      2008-02-15 15:47:41,610 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:47:41,610 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:47:41,610 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:49:41,615 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:49:41>
                      2008-02-15 15:49:41,615 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:49:41,615 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:49:41,615 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:49:51,615 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:49:51>
                      2008-02-15 15:49:51,615 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:49:51,615 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:49:51,615 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:51:51,620 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:51:51>
                      2008-02-15 15:51:51,620 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:51:51,620 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:51:51,620 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:52:01,620 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:52:01>
                      2008-02-15 15:52:01,620 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:52:01,620 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:52:01,620 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:52:29,762 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
                      2008-02-15 15:54:01,623 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:54:01>
                      2008-02-15 15:54:01,623 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:54:01,623 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:54:01,623 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:54:11,623 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:54:11>
                      2008-02-15 15:54:11,623 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:54:11,623 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:54:11,623 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:56:11,626 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:56:11>
                      2008-02-15 15:56:11,626 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:56:11,626 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:56:11,626 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:56:21,626 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:56:21>
                      2008-02-15 15:56:21,626 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:56:21,626 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:56:21,626 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:58:21,628 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 15:58:21>
                      2008-02-15 15:58:21,628 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 15:58:21,628 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 15:58:21,628 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 15:58:31,753 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 15:58:31>
                      2008-02-15 15:58:31,753 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 15:58:31,753 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 15:58:31,753 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 15:59:59,770 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
                      2008-02-15 16:00:31,754 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 16:00:31>
                      2008-02-15 16:00:31,754 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 16:00:31,754 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 16:00:31,754 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 16:00:41,770 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 16:00:41>
                      2008-02-15 16:00:41,770 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 16:00:41,770 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 16:00:41,770 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 16:02:41,803 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 16:02:41>
                      2008-02-15 16:02:41,803 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 16:02:41,803 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 16:02:41,803 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 16:02:51,818 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 16:02:51>
                      2008-02-15 16:02:51,818 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 16:02:51,818 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 16:02:51,818 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 16:04:51,820 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 16:04:51>
                      2008-02-15 16:04:51,820 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 16:04:51,820 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 16:04:51,820 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 16:05:01,820 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 16:05:01>
                      2008-02-15 16:05:01,820 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 16:05:01,820 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 16:05:01,820 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 16:07:01,823 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 16:07:01>
                      2008-02-15 16:07:01,823 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 16:07:01,823 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 16:07:01,823 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 16:07:11,824 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 16:07:11>
                      2008-02-15 16:07:11,824 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 16:07:11,824 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 16:07:11,824 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 16:07:29,777 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
                      2008-02-15 16:09:11,827 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 16:09:11>
                      2008-02-15 16:09:11,827 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 16:09:11,827 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 16:09:11,827 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 16:09:21,827 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 16:09:21>
                      2008-02-15 16:09:21,827 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 16:09:21,827 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 16:09:21,827 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 16:11:21,828 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 16:11:21>
                      2008-02-15 16:11:21,828 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 16:11:21,828 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 16:11:21,828 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 16:11:31,828 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 16:11:31>
                      2008-02-15 16:11:31,828 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 16:11:31,828 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 16:11:31,828 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 16:13:31,830 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 16:13:31>
                      2008-02-15 16:13:31,830 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 16:13:31,830 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 16:13:31,830 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 16:13:41,830 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 16:13:41>
                      2008-02-15 16:13:41,830 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 16:13:41,830 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 16:13:41,830 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
                      2008-02-15 16:14:59,784 DEBUG [org.jboss.resource.connectionmanager.IdleRemover] run: IdleRemover notifying pools, interval: 450000
                      2008-02-15 16:15:41,831 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Fri, 15 Feb 2008 16:15:41>
                      2008-02-15 16:15:41,831 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
                      2008-02-15 16:15:41,831 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
                      2008-02-15 16:15:41,831 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
                      2008-02-15 16:15:51,831 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Fri, 15 Feb 2008 16:15:51>
                      2008-02-15 16:15:51,831 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
                      2008-02-15 16:15:51,831 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
                      2008-02-15 16:15:51,831 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass


                      What should I do?