7 Replies Latest reply on Jun 20, 2013 5:59 PM by Scott Marlow

    What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?

    Adrian Everett Newbie

      In most circumstances, data is rolled back correctly when an exception is encountered during a container managed transaction, but not always. However, if I set the jta attribute to true, it appears that transactions always correctly roll back.

       

      I'm confused why I would need this setting to be true for the container managed transactions to work correctly, all the time, and what the implications would be to just leave it set to true.  We don't need the advanced capabilities of it, because we don't run different databases.

       

      The code uses an entity manager to persist a new record via a JPA Bean, and after that is done, a secondary method is called to insert some related data in another table.  As a test, I commented out the code in the secondary method and changed it to throw a new RunTimeException.  The exception is getting passed back to the Session bean, but the inserted row is not rolled back.  However, once I set the jta attribute to true, the inserted row is rolled back.

       

       

       

      We have our datasource configured like this:

       

                      <datasource jta="false" jndi-name="java:/jdbc/testDB" pool-name="jdbc/testDB" enabled="true">
                          <connection-url>jdbc:jtds:sqlserver://myserver:1433/testDB;ssl=require</connection-url>
                          <driver>jtds</driver>
                          <security>
                              <security-domain>SqlServerDbRealm</security-domain>
                          </security>
                      </datasource>
      

       

       

       

      Our persistence unit looks like this:

       

         <persistence-unit name="myEjb30" >
              <jta-data-source>java:/jdbc/testDB</jta-data-source>
              <jar-file>myBusinessLogic.jar</jar-file>
              <class>mil.tis.test.TableA</class>
      
              <properties>
                  <!-- <property name="hibernate.hbm2ddl.auto" value="create"/> -->
                  <property name="hibernate.dialect" value="org.hibernate.dialect.SQLServerDialect"/>
                  <property name="hibernate.jdbc.batch_size" value="30"/>
                  <property name="hibernate.fetchSize" value="50"/>
                  <property name="hibernate.max_fetch_depth" value="8"/>
                  <property name="jboss.entity.manager.jndi.name" value="java:/tisEjb30Entity"/>
                  <property name="jboss.entity.manager.factory.jndi.name" value="java:/tisEjb30Factory"/>
              </properties>
          </persistence-unit>
      

       

      The session bean that is getting called from the Web Tier is annotated like this:

       

      @Stateful(name = "PlanManager30")
      @TransactionAttribute(REQUIRED)
      @RolesAllowed("tisUser")
      public class PlanManager30Bean implements PlanManager30
      {
      
          @PersistenceContext(unitName = "myEjb30")
          protected EntityManager entityManager;
      
      
      
          @Override
          @TransactionAttribute(REQUIRED)
          public TableA  insertDataInPrimaryTable(Integer key)   throws Exception
         {  
                      TableA tableA = new TableA(key);
                      entityManager.persist(tableA);
      
                      // call secondary method to create some related data in another table
                      HelperClass.createRelatedDataInSecondaryTables(entityManager, key);  // this is hard-coded to throw a RuntimeException
           }
      }
      

       

      The JPA Bean that gets the inserted row:

       

      @Entity
      @Table(name = "table_a")
      @TransactionAttribute(REQUIRED)
      @RolesAllowed("tisUser")
      
       public class TableA implements Serializable {
        ......
       }
      

       

       

       

      If jta="false", then the data in tableA will still be in the database after the Exception made its way back to the JSF Managed Bean.  If I set jta="true", then the data in tableA will be rolled back once the Exception makes its way back to the web tier.

       

      Can anyone help me shed a little light on this?

       

      Thanks,

      Adrian

        • 1. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?
          Scott Marlow Master

          Turn TRACE logging on and look at the server.log output.  https://docs.jboss.org/author/display/WFLY8/JPA+Reference+Guide#JPAReferenceGuide-Troubleshooting will help you get started.

           

          You will want to enable org.jboss.as.jpa, org.hibernate.SQL, com.arjuna).  You could also include jca if you like (org.jboss.jca).

           

          You can post the log output (with jta set to false) and we can discuss to explain why.

          • 2. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?
            Adrian Everett Newbie

            I don't know if it makes a difference, but unlike the simplified example I pasted above, the actualy JPA bean that doesn't get rolled back has an auto-generated identify key.  Perhaps that has something to do with it.

             

            I.e.,

             

                @Id

                @GeneratedValue(strategy = GenerationType.IDENTITY)

                @Column(name = "table_id", columnDefinition = "long")

                private Long tableId= null;

            • 3. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?
              Adrian Everett Newbie

              The following two replies will contain the trace files, beginning at the start of the transaction.

               

              When the JSF Managed bean is about to call the Session Bean method, you'll see "In the JSF Managed Bean." in the log.

              When the Session Bean is hit, you'll see "Method called  in Session Bean" in the log (there are two spaces between 'called' and 'in').

              When the exception is caught in the Session Bean, you'll see " About to re-throw the exception back" in the log.

               

               

               

              Without JTA

              [stdout] (http--0.0.0.0-3809-6) In the JSF Managed bean.  About to call Session Bean method.... 13:33:08,622 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) created new TransactionScopedEntityManager for unit name=Aims.ear/myBusinessLogic.jar#myEjb30
              13:33:08,623 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.begin
              13:33:08,623 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) StateManager::StateManager( 2, 0 )
              13:33:08,623 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::BasicAction()
              13:33:08,624 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::Begin() for action-id 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b
              13:33:08,624 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::actionInitialise() for action-id 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b
              13:33:08,625 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ActionHierarchy::ActionHierarchy(5)
              13:33:08,625 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ActionHierarchy::add(0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b, 1)
              13:33:08,625 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::addChildThread () action 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b adding Thread[http--0.0.0.0-3809-6,5,main]
              13:33:08,626 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::addChildThread () action 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b adding Thread[http--0.0.0.0-3809-6,5,main] result = true
              13:33:08,627 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) TransactionReaper::insert ( BasicAction: 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b status: ActionStatus.RUNNING, 300 )
              13:33:08,627 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b status: ActionStatus.RUNNING, 300 )
              13:33:08,628 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:33:08,628 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:33:08,629 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionStatus
              13:33:08,629 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,630 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,630 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:33:08,630 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,631 INFO  [stdout] (http--0.0.0.0-3809-6) Method called  in Session Bean. 13:33:08,632 TRACE [org.jboss.jca.core.connectionmanager.NoTxConnectionManager] (http--0.0.0.0-3809-6) Subject: Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              
              
              13:33:08,633 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 18aaf26
              Method: getConnection(Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              , null)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: beff97
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl
                Object: 17e36ca
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
                1f112a3 (NORMAL)
              InUse:
              Statistics:
                ActiveCount: 1
                AvailableCount: 20
                AverageBlockingTime: 0
                AverageCreationTime: 135
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 135
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 135
              13:33:08,640 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) supplying ManagedConnection from pool: org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588609 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,642 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Got connection from pool: org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588609 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,644 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) registering connection from connection manager: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl@17e36ca, connection : org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@e04c79, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,667 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl@17e36ca, connection: org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@e04c79, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,669 TRACE [org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener] (http--0.0.0.0-3809-6) unregisterConnection: 0 handles left
              13:33:08,669 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 18aaf26
              Method: returnConnection(1f112a3, false)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: beff97
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl
                Object: 17e36ca
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
              InUse:
                1f112a3 (NORMAL)
              Statistics:
                ActiveCount: 1
                AvailableCount: 19
                AverageBlockingTime: 0
                AverageCreationTime: 135
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 135
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 135
              13:33:08,673 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Returning connection to pool org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588673 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,675 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,676 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:33:08,676 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,677 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionStatus
              13:33:08,677 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,677 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,678 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getRollbackOnly
              13:33:08,678 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,678 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,679 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,679 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,679 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:33:08,680 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,680 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,680 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,681 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,681 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,681 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) http--0.0.0.0-3809-6:[transaction scoped EntityManager]: created entity manager session TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b status: ActionStatus.RUNNING >
              13:33:08,682 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:33:08,683 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,683 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.putResource
              13:33:08,683 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,684 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,684 TRACE [org.jboss.as.jpa] (http--0.0.0.0-3809-6) createNamedQuery name 'TableABean.checkWidgetForAUnit' took 9ms
              13:33:08,685 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,685 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,686 TRACE [org.jboss.jca.core.connectionmanager.NoTxConnectionManager] (http--0.0.0.0-3809-6) Subject: Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              
              
              13:33:08,687 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 18aaf26
              Method: getConnection(Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              , null)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: beff97
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl
                Object: 17e36ca
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
                1f112a3 (NORMAL)
              InUse:
              Statistics:
                ActiveCount: 1
                AvailableCount: 20
                AverageBlockingTime: 0
                AverageCreationTime: 135
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 135
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 135
              13:33:08,696 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) supplying ManagedConnection from pool: org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588673 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,698 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Got connection from pool: org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588673 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,700 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) registering connection from connection manager: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl@17e36ca, connection : org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@851bd3, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,702 DEBUG [org.hibernate.SQL] (http--0.0.0.0-3809-6) 
                  select
                      top 2 tableA0_.keyColumn1 as KeyCol1_35_,
                      tableA0_.column2 as column2_35_,
                      tableA0_.column3 as column3_35_,
                      tableA0_.column4 as column4_35_,
                      tableA0_.column5 as column5_35_,
                      tableA0_.column6 as column6_35_,
                      tableA0_.column7 as column7_35_,
                      tableA0_.column8 as column8_35_,
                      tableA0_.column9 as column9_35_,
                      tableA0_.column10 as column10_35_,
                      tableA0_.column11 as column11_35_,
                      tableA0_.column12 as column12_35_,
                      tableA0_.column13 as column13_35_,
                      tableA0_.column14 as column14_35_,
                      tableA0_.column15 as column15_35_,
                      tableA0_.column16 as column16_35_,
                      tableA0_.column17 as column17_35_,
                      tableA0_.column18 as column18_35_,
                      tableA0_.column19 as column19_35_,
                      tableA0_.column20 as column20_35_,
                      tableA0_.column21 as column21_35_,
                      tableA0_.column22 as column22_35_,
                      tableA0_.column23 as column23_35_,
                      tableA0_.column24 as column24_35_,
                      tableA0_.column25 as column25_35_,
                      tableA0_.column26 as column26_35_,
                      tableA0_.column27 as column27_35_,
                      tableA0_.column28 as column28_35_,
                      tableA0_.column29 as column29_35_,
                      tableA0_.column30 as column30_35_,
                      tableA0_.column31 as column31_35_,
                      tableA0_.column32 as column32_35_,
                      tableA0_.column33 as column33_35_ 
                  from
                      table_A tableA0_ 
                  where
                      tableA0_.key_column1=? 
              13:33:08,714 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl@17e36ca, connection: org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@851bd3, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,716 TRACE [org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener] (http--0.0.0.0-3809-6) unregisterConnection: 0 handles left
              13:33:08,717 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 18aaf26
              Method: returnConnection(1f112a3, false)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: beff97
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl
                Object: 17e36ca
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
              InUse:
                1f112a3 (NORMAL)
              Statistics:
                ActiveCount: 1
                AvailableCount: 19
                AverageBlockingTime: 0
                AverageCreationTime: 135
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 135
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 135
              13:33:08,721 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Returning connection to pool org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588721 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,723 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,723 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,724 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,724 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,724 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:33:08,725 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,725 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) http--0.0.0.0-3809-6:[transaction scoped EntityManager]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b status: ActionStatus.RUNNING >
              13:33:08,729 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,729 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,734 TRACE [org.jboss.jca.core.connectionmanager.NoTxConnectionManager] (http--0.0.0.0-3809-6) Subject: Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              
              
              13:33:08,735 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 18aaf26
              Method: getConnection(Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              , null)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: beff97
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl
                Object: 17e36ca
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
                1f112a3 (NORMAL)
              InUse:
              Statistics:
                ActiveCount: 1
                AvailableCount: 20
                AverageBlockingTime: 0
                AverageCreationTime: 135
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 135
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 135
              13:33:08,740 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) supplying ManagedConnection from pool: org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588721 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,742 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Got connection from pool: org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588721 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,744 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) registering connection from connection manager: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl@17e36ca, connection : org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@110b9be, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,746 DEBUG [org.hibernate.SQL] (http--0.0.0.0-3809-6) 
                  insert 
                  into
                      table_A
                      (KeyColumn1, column2,column3, column4, column5, column6,column7, column8, column9, column10, column11, column12, column13, column14, column15, column16, column17, column18, column19, column20, column21, column22, column23, column24, column25, column26, column27, column28, column29, column30, column31, column32) 
                  values
                      (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
              13:33:08,755 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl@17e36ca, connection: org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@110b9be, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,756 TRACE [org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener] (http--0.0.0.0-3809-6) unregisterConnection: 0 handles left
              13:33:08,757 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 18aaf26
              Method: returnConnection(1f112a3, false)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: beff97
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl
                Object: 17e36ca
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
              InUse:
                1f112a3 (NORMAL)
              Statistics:
                ActiveCount: 1
                AvailableCount: 19
                AverageBlockingTime: 0
                AverageCreationTime: 135
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 135
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 135
              13:33:08,761 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Returning connection to pool org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588761 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,764 TRACE [org.jboss.as.jpa] (http--0.0.0.0-3809-6) persist entityClass 'mil.tis.Widget.TableABean' took 40ms
              13:33:08,765 ERROR [mil.tis.Widget.WidgetManagerImpl] (http--0.0.0.0-3809-6) java.lang.RuntimeException: EXCEPTION!
              13:33:08,765 INFO  [stdout] (http--0.0.0.0-3809-6) The try-catch block in the Session bean method was hit. 13:33:08,766 INFO  [stdout] (http--0.0.0.0-3809-6) About to re-throw the exception back to the JSF Managed bean in the Web Tier. 13:33:08,766 INFO  [stdout] (http--0.0.0.0-3809-6) ----------------------------------------------------- 13:33:08,767 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,767 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,767 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,767 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,767 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,768 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,768 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,768 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,768 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,768 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,769 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,769 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,769 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,769 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,770 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,770 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,770 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,770 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,771 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,771 INFO  [stdout] (http--0.0.0.0-3809-6)   13:33:08,772 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.setRollbackOnly
              13:33:08,772 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::preventCommit( BasicAction: 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b status: ActionStatus.RUNNING)
              13:33:08,773 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,773 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.rollback
              13:33:08,773 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.rollbackAndDisassociate
              13:33:08,774 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::Abort() for action-id 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b
              13:33:08,774 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:33:08,775 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) http--0.0.0.0-3809-6:[transaction scoped EntityManager]: closing entity managersession
              13:33:08,775 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,776 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,776 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:33:08,776 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:33:08,777 INFO  [org.jboss.as.ejb3] (http--0.0.0.0-3809-6) JBAS014101: Failed to find SFSB instance with session ID {[85, 49, 27, -41, -47, 51, 77, -106, -73, -3, 47, -31, -94, 119, -62, 57]} in cache
              13:33:08,778 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::removeChildThread () action 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b removing TSThread:2
              13:33:08,778 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::removeChildThread () action 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b removing TSThread:2 result = true
              13:33:08,779 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) TransactionReaper::remove ( BasicAction: 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3b status: ActionStatus.ABORTED )
              13:33:08,780 ERROR [org.jboss.ejb3.invocation] (http--0.0.0.0-3809-6) JBAS014134: EJB Invocation failed on component WidgetManager30Bean for method public abstract mil.tis.Widget.TableABean mil.tis.Widget.WidgetManager30.createData(java.lang.String,mil.tis.Widget.TableABean,java.util.Collection) throws java.lang.Exception: javax.ejb.EJBException: java.lang.RuntimeException: EXCEPTION!
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.handleExceptionInOurTx(CMTTxInterceptor.java:166) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:230) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.required(CMTTxInterceptor.java:304) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:190) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.remote.EJBRemoteTransactionPropagatingInterceptor.processInvocation(EJBRemoteTransactionPropagatingInterceptor.java:80) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:32) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.as.ejb3.remote.LocalEjbReceiver.processInvocation(LocalEjbReceiver.java:179) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.ejb.client.EJBClientInvocationContext.sendRequest(EJBClientInvocationContext.java:179) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.TransactionInterceptor.handleInvocation(TransactionInterceptor.java:43) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBClientInvocationContext.sendRequest(EJBClientInvocationContext.java:181) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.ReceiverInterceptor.handleInvocation(ReceiverInterceptor.java:128) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBClientInvocationContext.sendRequest(EJBClientInvocationContext.java:181) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBInvocationHandler.doInvoke(EJBInvocationHandler.java:136) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBInvocationHandler.doInvoke(EJBInvocationHandler.java:121) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBInvocationHandler.invoke(EJBInvocationHandler.java:104) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at $Proxy69.createData(Unknown Source)          at mil.tis.jsp.pages.movementWidgetning.WidgetManager.PageTOSourceSearch.createData(PageTOSourceSearch.java:576)
                        at mil.tis.jsp.pages.movementWidgetning.WidgetManager.PageTOSourceSearch.createNewWidget(PageTOSourceSearch.java:367)
                        at mil.tis.jsp.pages.movementWidgetning.WidgetManager.PageRTTATOImport.createWidgetWithRTTAUnits(PageRTTATOImport.java:178)
                        at mil.tis.jsp.pages.movementWidgetning.WidgetManager.PageRTTATOImport.importRTTAFile(PageRTTATOImport.java:137)
                        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_07]
                        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_07]
                        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_07]
                        at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_07]
                        at org.apache.el.parser.AstValue.invoke(AstValue.java:262)
                        at org.apache.el.MethodExpressionImpl.invoke(MethodExpressionImpl.java:278)
                        at com.sun.faces.facelets.el.TagMethodExpression.invoke(TagMethodExpression.java:105)
                        at javax.faces.component.MethodBindingMethodExpressionAdapter.invoke(MethodBindingMethodExpressionAdapter.java:88)
                        at com.sun.faces.application.ActionListenerImpl.processAction(ActionListenerImpl.java:102)
                        at javax.faces.component.UICommand.broadcast(UICommand.java:315)
                        at javax.faces.component.UIViewRoot.broadcastEvents(UIViewRoot.java:794)
                        at javax.faces.component.UIViewRoot.processApplication(UIViewRoot.java:1259)
                        at com.sun.faces.lifecycle.InvokeApplicationPhase.execute(InvokeApplicationPhase.java:81)
                        at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:101)
                        at com.sun.faces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:118)
                        at javax.faces.webapp.FacesServlet.service(FacesServlet.java:593)
                        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329)
                        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
                        at org.primefaces.webapp.filter.FileUploadFilter.doFilter(FileUploadFilter.java:77)
                        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
                        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
                        at mil.tis.authentication.AssetManagementAuthenticationFilter.doFilter(AssetManagementAuthenticationFilter.java:139)
                        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
                        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
                        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275)
                        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
                        at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:397)
                        at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:153)
                        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155)
                        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
                        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
                        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:368)
                        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:877)
                        at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:671)
                        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:930)
                        at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_07]
              Caused by: java.lang.RuntimeException: EXCEPTION!
                        at mil.tis.nonprofitOrg.nonprofitOrgManagerImpl.createnonprofitOrgBasedOnRTTA(nonprofitOrgManagerImpl.java:372) [myBusinessLogic.jar:]
                        at mil.tis.Widget.WidgetManagerImpl.createData(WidgetManagerImpl.java:395) [myBusinessLogic.jar:]
                        at mil.tis.Widget.WidgetManager30Bean.createData(WidgetManager30Bean.java:535) [myBusinessLogic.jar:]
                        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_07]
                        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_07]
                        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_07]
                        at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_07]
                        at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.jpa.interceptor.SBInvocationInterceptor.processInvocation(SBInvocationInterceptor.java:47) [jboss-as-jpa-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.jpa.interceptor.SFSBInvocationInterceptor.processInvocation(SFSBInvocationInterceptor.java:58) [jboss-as-jpa-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.stateful.StatefulSessionSynchronizationInterceptor.processInvocation(StatefulSessionSynchronizationInterceptor.java:156) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.stateful.StatefulComponentInstanceInterceptor.processInvocation(StatefulComponentInstanceInterceptor.java:66) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:228) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        ... 67 more
              
              
              13:33:08,857 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) created new TransactionScopedEntityManager for unit name=Aims.ear/myBusinessLogic.jar#myEjb30
              13:33:08,857 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.begin
              13:33:08,858 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) StateManager::StateManager( 2, 0 )
              13:33:08,858 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::BasicAction()
              13:33:08,858 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::Begin() for action-id 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f
              13:33:08,859 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::actionInitialise() for action-id 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f
              13:33:08,860 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ActionHierarchy::ActionHierarchy(5)
              13:33:08,860 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ActionHierarchy::add(0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f, 1)
              13:33:08,860 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::addChildThread () action 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f adding Thread[http--0.0.0.0-3809-6,5,main]
              13:33:08,861 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::addChildThread () action 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f adding Thread[http--0.0.0.0-3809-6,5,main] result = true
              13:33:08,862 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) TransactionReaper::insert ( BasicAction: 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f status: ActionStatus.RUNNING, 300 )
              13:33:08,862 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f status: ActionStatus.RUNNING, 300 )
              13:33:08,863 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:33:08,864 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:33:08,864 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionStatus
              13:33:08,865 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,865 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,865 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:33:08,866 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,866 TRACE [org.jboss.jca.core.connectionmanager.NoTxConnectionManager] (http--0.0.0.0-3809-6) Subject: Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              
              
              13:33:08,868 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 18aaf26
              Method: getConnection(Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              , null)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: beff97
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl
                Object: 17e36ca
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
                1f112a3 (NORMAL)
              InUse:
              Statistics:
                ActiveCount: 1
                AvailableCount: 20
                AverageBlockingTime: 0
                AverageCreationTime: 135
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 135
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 135
              13:33:08,873 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) supplying ManagedConnection from pool: org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588761 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,875 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Got connection from pool: org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588761 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,877 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) registering connection from connection manager: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl@17e36ca, connection : org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@705324, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,879 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl@17e36ca, connection: org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@705324, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,881 TRACE [org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener] (http--0.0.0.0-3809-6) unregisterConnection: 0 handles left
              13:33:08,882 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 18aaf26
              Method: returnConnection(1f112a3, false)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: beff97
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.notx.NoTxConnectionManagerImpl
                Object: 17e36ca
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
              InUse:
                1f112a3 (NORMAL)
              Statistics:
                ActiveCount: 1
                AvailableCount: 19
                AverageBlockingTime: 0
                AverageCreationTime: 135
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 135
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 135
              13:33:08,886 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Returning connection to pool org.jboss.jca.core.connectionmanager.listener.NoTxConnectionListener@1f112a3[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1fea6ff connection handles=0 lastUse=1371749588886 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@1ad4fbf pool internal context=SemaphoreArrayListManagedConnectionPool@18aaf26[pool=jdbc/myDB]]
              13:33:08,888 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:33:08,889 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.commit
              13:33:08,889 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.commitAndDisassociate
              13:33:08,889 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.beforeCompletion
              13:33:08,890 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::End() for action-id 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f
              13:33:08,890 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:33:08,891 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::removeChildThread () action 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f removing TSThread:2
              13:33:08,891 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::removeChildThread () action 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f removing TSThread:2 result = true
              13:33:08,892 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) TransactionReaper::remove ( BasicAction: 0:ffffc0a82a4b:6d8c5a11:51c33c8e:3f status: ActionStatus.COMMITTED )
              13:33:08,907 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,908 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,932 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,933 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,932 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,933 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,933 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,933 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,938 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,936 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,937 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,935 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,941 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-4) BaseTransaction.getStatus
              13:33:08,940 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:08,939 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,940 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-2) BaseTransaction.getStatus
              13:33:08,939 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,943 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-1) BaseTransaction.getStatus
              13:33:08,942 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,942 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-3) BaseTransaction.getStatus
              13:33:08,944 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-5) BaseTransaction.getStatus
              13:33:08,946 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,947 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-4) BaseTransaction.getStatus
              13:33:08,947 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,950 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:08,951 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:09,056 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1d1030c
              13:33:09,058 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1d1030c
              13:33:09,059 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:33:09,060 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:09,062 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@6c042d
              13:33:09,063 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              

               

              With JTA

              [stdout] (http--0.0.0.0-3809-6) In the JSF Managed bean.  About to call Session Bean method.... 13:35:10,427 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) created new TransactionScopedEntityManager for unit name=Aims.ear/myBusinessLogic.jar#myEjb30
              13:35:10,428 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.begin
              13:35:10,429 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) StateManager::StateManager( 2, 0 )
              13:35:10,429 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::BasicAction()
              13:35:10,429 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::Begin() for action-id 0:ffffc0a82a4b:3475f402:51c33d15:57
              13:35:10,430 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::actionInitialise() for action-id 0:ffffc0a82a4b:3475f402:51c33d15:57
              13:35:10,430 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ActionHierarchy::ActionHierarchy(5)
              13:35:10,431 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ActionHierarchy::add(0:ffffc0a82a4b:3475f402:51c33d15:57, 1)
              13:35:10,431 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::addChildThread () action 0:ffffc0a82a4b:3475f402:51c33d15:57 adding Thread[http--0.0.0.0-3809-6,5,main]
              13:35:10,432 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::addChildThread () action 0:ffffc0a82a4b:3475f402:51c33d15:57 adding Thread[http--0.0.0.0-3809-6,5,main] result = true
              13:35:10,433 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) TransactionReaper::insert ( BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING, 300 )
              13:35:10,433 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING, 300 )
              13:35:10,434 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,434 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,435 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionStatus
              13:35:10,435 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,435 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,436 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:35:10,436 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,437 INFO  [stdout] (http--0.0.0.0-3809-6) Method called  in Session Bean. 13:35:10,438 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--0.0.0.0-3809-6) Subject: Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              
              
              13:35:10,439 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,439 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--0.0.0.0-3809-6) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,440 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,440 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,441 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,441 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,441 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,442 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,442 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,443 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.putResource
              13:35:10,443 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,443 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,444 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,444 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 71bd0d
              Method: getConnection(Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              , null)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: 1bc552c
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl
                Object: 17bd63d
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
                132df48 (NORMAL)
              InUse:
              Statistics:
                ActiveCount: 1
                AvailableCount: 20
                AverageBlockingTime: 0
                AverageCreationTime: 14
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 14
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 14
              13:35:10,450 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) supplying ManagedConnection from pool: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null]
              13:35:10,452 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Got connection from pool tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null] tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,455 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,456 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,456 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,456 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,457 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,457 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.putResource
              13:35:10,457 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,458 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Using connection from pool tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null] tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,460 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,461 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,461 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,464 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,466 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:35:10,467 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,467 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Enlisting resource org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=false}]
              13:35:10,470 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.enlistResource ( LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] )
              13:35:10,470 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,471 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) StateManager::StateManager( 0:0:0:0:1 )
              13:35:10,471 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) AbstractRecord::AbstractRecord (0:0:0:0:1)
              13:35:10,471 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) LastResourceRecord()
              13:35:10,472 TRACE [org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl] (http--0.0.0.0-3809-6) start(< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >, 0)
              13:35:10,473 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) RecordList::insert(RecordList: empty) : appending /StateManager/AbstractRecord/LastResourceRecord for 0:0:0:0:1
              13:35:10,474 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Enlisted resource org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=false}]
              13:35:10,477 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,481 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@17bd63d, connection : org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@124d183, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,483 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) connectionClosed called mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168
              13:35:10,484 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@17bd63d, connection: org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@124d183, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,486 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) unregisterConnection: 0 handles left
              13:35:10,486 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168
              13:35:10,487 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,487 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,488 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,488 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionStatus
              13:35:10,489 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,489 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,489 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getRollbackOnly
              13:35:10,490 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,490 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,490 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,491 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,491 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:35:10,491 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,492 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,492 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,492 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,493 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,493 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) http--0.0.0.0-3809-6:[transaction scoped EntityManager]: created entity manager session TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,494 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:35:10,495 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,495 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.putResource
              13:35:10,495 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,496 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,496 TRACE [org.jboss.as.jpa] (http--0.0.0.0-3809-6) createNamedQuery name 'TableABean.checkWidgetForAUnit' took 9ms
              13:35:10,497 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,497 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,498 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--0.0.0.0-3809-6) Subject: Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              
              
              13:35:10,499 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,499 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--0.0.0.0-3809-6) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,500 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,500 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,501 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,501 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,502 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,502 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,502 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Previous connection tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,506 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,506 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,507 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,511 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.equals
              13:35:10,511 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,515 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,518 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@17bd63d, connection : org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@d1afad, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,520 DEBUG [org.hibernate.SQL] (http--0.0.0.0-3809-6) 
                  select
                      top 2 tableA0_.keyColumn1 as KeyCol1_35_,
                      tableA0_.column2 as column2_35_,
                      tableA0_.column3 as column3_35_,
                      tableA0_.column4 as column4_35_,
                      tableA0_.column5 as column5_35_,
                      tableA0_.column6 as column6_35_,
                      tableA0_.column7 as column7_35_,
                      tableA0_.column8 as column8_35_,
                      tableA0_.column9 as column9_35_,
                      tableA0_.column10 as column10_35_,
                      tableA0_.column11 as column11_35_,
                      tableA0_.column12 as column12_35_,
                      tableA0_.column13 as column13_35_,
                      tableA0_.column14 as column14_35_,
                      tableA0_.column15 as column15_35_,
                      tableA0_.column16 as column16_35_,
                      tableA0_.column17 as column17_35_,
                      tableA0_.column18 as column18_35_,
                      tableA0_.column19 as column19_35_,
                      tableA0_.column20 as column20_35_,
                      tableA0_.column21 as column21_35_,
                      tableA0_.column22 as column22_35_,
                      tableA0_.column23 as column23_35_,
                      tableA0_.column24 as column24_35_,
                      tableA0_.column25 as column25_35_,
                      tableA0_.column26 as column26_35_,
                      tableA0_.column27 as column27_35_,
                      tableA0_.column28 as column28_35_,
                      tableA0_.column29 as column29_35_,
                      tableA0_.column30 as column30_35_,
                      tableA0_.column31 as column31_35_,
                      tableA0_.column32 as column32_35_,
                      tableA0_.column33 as column33_35_ 
                  from
                      table_A tableA0_ 
                  where
                      tableA0_.key_column1=? 
              13:35:10,530 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) connectionClosed called mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168
              13:35:10,531 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@17bd63d, connection: org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@d1afad, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,532 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) unregisterConnection: 0 handles left
              13:35:10,533 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168
              13:35:10,534 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,534 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,534 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,535 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,535 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,536 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,536 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) http--0.0.0.0-3809-6:[transaction scoped EntityManager]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,539 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,539 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,543 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--0.0.0.0-3809-6) Subject: Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              
              
              13:35:10,544 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,544 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--0.0.0.0-3809-6) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,545 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,545 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,546 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,546 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,547 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,547 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,547 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Previous connection tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,551 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,551 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,552 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,556 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.equals
              13:35:10,556 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,560 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync20187306{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING >
              13:35:10,563 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@17bd63d, connection : org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@1262a10, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,565 DEBUG [org.hibernate.SQL] (http--0.0.0.0-3809-6) 
                  insert 
                  into
                      table_A
                      (KeyColumn1, column2,column3, column4, column5, column6,column7, column8, column9, column10, column11, column12, column13, column14, column15, column16, column17, column18, column19, column20, column21, column22, column23, column24, column25, column26, column27, column28, column29, column30, column31, column32) 
                  values
                      (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)
              13:35:10,573 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) connectionClosed called mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168
              13:35:10,574 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@17bd63d, connection: org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@1262a10, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,576 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) unregisterConnection: 0 handles left
              13:35:10,576 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168
              13:35:10,577 TRACE [org.jboss.as.jpa] (http--0.0.0.0-3809-6) persist entityClass 'mil.tis.Widget.TableABean' took 43ms
              13:35:10,578 ERROR [mil.tis.Widget.WidgetManagerImpl] (http--0.0.0.0-3809-6) java.lang.RuntimeException: EXCEPTION!
              13:35:10,578 INFO  [stdout] (http--0.0.0.0-3809-6) The try-catch block in the Session bean method was hit. 13:35:10,579 INFO  [stdout] (http--0.0.0.0-3809-6) About to re-throw the exception back to the JSF Managed bean in the Web Tier. 13:35:10,579 INFO  [stdout] (http--0.0.0.0-3809-6) ----------------------------------------------------- 13:35:10,580 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,580 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,580 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,580 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,581 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,581 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,581 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,581 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,581 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,582 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,582 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,582 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,582 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,583 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,583 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,583 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,583 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,584 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,584 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,584 INFO  [stdout] (http--0.0.0.0-3809-6)   13:35:10,585 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.setRollbackOnly
              13:35:10,585 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::preventCommit( BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.RUNNING)
              13:35:10,586 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,586 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.rollback
              13:35:10,586 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.rollbackAndDisassociate
              13:35:10,587 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::Abort() for action-id 0:ffffc0a82a4b:3475f402:51c33d15:57
              13:35:10,587 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::doAbort (LastResourceRecord(XAOnePhaseResource(LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:57, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:5a, subordinatenodename=null, eis_name=unknown eis name >])))
              13:35:10,589 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) LastResourceRecord::topLevelAbort() for 0:0:0:0:1
              13:35:10,590 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:35:10,591 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) http--0.0.0.0-3809-6:[transaction scoped EntityManager]: closing entity managersession
              13:35:10,591 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,592 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,592 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:35:10,592 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:35:10,593 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) afterCompletion(4) isTrackByTx=true for org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710417 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null]
              13:35:10,595 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 71bd0d
              Method: returnConnection(132df48, false)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: 1bc552c
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl
                Object: 17bd63d
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
              InUse:
                132df48 (NORMAL)
              Statistics:
                ActiveCount: 1
                AvailableCount: 19
                AverageBlockingTime: 0
                AverageCreationTime: 14
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 14
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 14
              13:35:10,600 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Returning connection to pool org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null]
              13:35:10,602 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:35:10,603 INFO  [org.jboss.as.ejb3] (http--0.0.0.0-3809-6) JBAS014101: Failed to find SFSB instance with session ID {[16, 120, -14, 4, -94, -20, 75, -120, -119, -73, 69, -94, 5, 71, -118, -72]} in cache
              13:35:10,604 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::removeChildThread () action 0:ffffc0a82a4b:3475f402:51c33d15:57 removing TSThread:2
              13:35:10,604 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::removeChildThread () action 0:ffffc0a82a4b:3475f402:51c33d15:57 removing TSThread:2 result = true
              13:35:10,605 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) TransactionReaper::remove ( BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:57 status: ActionStatus.ABORTED )
              13:35:10,606 ERROR [org.jboss.ejb3.invocation] (http--0.0.0.0-3809-6) JBAS014134: EJB Invocation failed on component WidgetManager30Bean for method public abstract mil.tis.Widget.TableABean mil.tis.Widget.WidgetManager30.createData(java.lang.String,mil.tis.Widget.TableABean,java.util.Collection) throws java.lang.Exception: javax.ejb.EJBException: java.lang.RuntimeException: EXCEPTION!
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.handleExceptionInOurTx(CMTTxInterceptor.java:166) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:230) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.required(CMTTxInterceptor.java:304) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:190) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.remote.EJBRemoteTransactionPropagatingInterceptor.processInvocation(EJBRemoteTransactionPropagatingInterceptor.java:80) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:32) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.TCCLInterceptor.processInvocation(TCCLInterceptor.java:45) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.as.ejb3.remote.LocalEjbReceiver.processInvocation(LocalEjbReceiver.java:179) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.ejb.client.EJBClientInvocationContext.sendRequest(EJBClientInvocationContext.java:179) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.TransactionInterceptor.handleInvocation(TransactionInterceptor.java:43) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBClientInvocationContext.sendRequest(EJBClientInvocationContext.java:181) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.ReceiverInterceptor.handleInvocation(ReceiverInterceptor.java:128) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBClientInvocationContext.sendRequest(EJBClientInvocationContext.java:181) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBInvocationHandler.doInvoke(EJBInvocationHandler.java:136) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBInvocationHandler.doInvoke(EJBInvocationHandler.java:121) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at org.jboss.ejb.client.EJBInvocationHandler.invoke(EJBInvocationHandler.java:104) [jboss-ejb-client-1.0.5.Final.jar:1.0.5.Final]
                        at $Proxy69.createData(Unknown Source)          at mil.tis.jsp.pages.movementWidgetning.WidgetManager.PageTOSourceSearch.createData(PageTOSourceSearch.java:576)
                        at mil.tis.jsp.pages.movementWidgetning.WidgetManager.PageTOSourceSearch.createNewWidget(PageTOSourceSearch.java:367)
                        at mil.tis.jsp.pages.movementWidgetning.WidgetManager.PageRTTATOImport.createWidgetWithRTTAUnits(PageRTTATOImport.java:178)
                        at mil.tis.jsp.pages.movementWidgetning.WidgetManager.PageRTTATOImport.importRTTAFile(PageRTTATOImport.java:137)
                        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_07]
                        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_07]
                        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_07]
                        at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_07]
                        at org.apache.el.parser.AstValue.invoke(AstValue.java:262)
                        at org.apache.el.MethodExpressionImpl.invoke(MethodExpressionImpl.java:278)
                        at com.sun.faces.facelets.el.TagMethodExpression.invoke(TagMethodExpression.java:105)
                        at javax.faces.component.MethodBindingMethodExpressionAdapter.invoke(MethodBindingMethodExpressionAdapter.java:88)
                        at com.sun.faces.application.ActionListenerImpl.processAction(ActionListenerImpl.java:102)
                        at javax.faces.component.UICommand.broadcast(UICommand.java:315)
                        at javax.faces.component.UIViewRoot.broadcastEvents(UIViewRoot.java:794)
                        at javax.faces.component.UIViewRoot.processApplication(UIViewRoot.java:1259)
                        at com.sun.faces.lifecycle.InvokeApplicationPhase.execute(InvokeApplicationPhase.java:81)
                        at com.sun.faces.lifecycle.Phase.doPhase(Phase.java:101)
                        at com.sun.faces.lifecycle.LifecycleImpl.execute(LifecycleImpl.java:118)
                        at javax.faces.webapp.FacesServlet.service(FacesServlet.java:593)
                        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:329)
                        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
                        at org.primefaces.webapp.filter.FileUploadFilter.doFilter(FileUploadFilter.java:77)
                        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
                        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
                        at mil.tis.authentication.AssetManagementAuthenticationFilter.doFilter(AssetManagementAuthenticationFilter.java:139)
                        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:280)
                        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:248)
                        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:275)
                        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:161)
                        at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:397)
                        at org.jboss.as.web.security.SecurityContextAssociationValve.invoke(SecurityContextAssociationValve.java:153)
                        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:155)
                        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
                        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
                        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:368)
                        at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:877)
                        at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:671)
                        at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:930)
                        at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_07]
              Caused by: java.lang.RuntimeException: EXCEPTION!
                        at mil.tis.nonprofitOrg.nonprofitOrgManagerImpl.createnonprofitOrgBasedOnRTTA(nonprofitOrgManagerImpl.java:372) [myBusinessLogic.jar:]
                        at mil.tis.Widget.WidgetManagerImpl.createData(WidgetManagerImpl.java:395) [myBusinessLogic.jar:]
                        at mil.tis.Widget.WidgetManager30Bean.createData(WidgetManager30Bean.java:535) [myBusinessLogic.jar:]
                        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_07]
                        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_07]
                        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_07]
                        at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_07]
                        at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:36) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.jpa.interceptor.SBInvocationInterceptor.processInvocation(SBInvocationInterceptor.java:47) [jboss-as-jpa-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.jpa.interceptor.SFSBInvocationInterceptor.processInvocation(SFSBInvocationInterceptor.java:58) [jboss-as-jpa-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.stateful.StatefulSessionSynchronizationInterceptor.processInvocation(StatefulSessionSynchronizationInterceptor.java:156) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53) [jboss-as-ee-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.component.stateful.StatefulComponentInstanceInterceptor.processInvocation(StatefulComponentInstanceInterceptor.java:66) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:288) [jboss-invocation-1.1.1.Final.jar:1.1.1.Final]
                        at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInOurTx(CMTTxInterceptor.java:228) [jboss-as-ejb3-7.1.1.Final.jar:7.1.1.Final]
                        ... 67 more
              
              
              13:35:10,684 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) created new TransactionScopedEntityManager for unit name=Aims.ear/myBusinessLogic.jar#myEjb30
              13:35:10,684 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.begin
              13:35:10,685 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) StateManager::StateManager( 2, 0 )
              13:35:10,685 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::BasicAction()
              13:35:10,686 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::Begin() for action-id 0:ffffc0a82a4b:3475f402:51c33d15:5d
              13:35:10,686 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::actionInitialise() for action-id 0:ffffc0a82a4b:3475f402:51c33d15:5d
              13:35:10,687 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ActionHierarchy::ActionHierarchy(5)
              13:35:10,687 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ActionHierarchy::add(0:ffffc0a82a4b:3475f402:51c33d15:5d, 1)
              13:35:10,687 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::addChildThread () action 0:ffffc0a82a4b:3475f402:51c33d15:5d adding Thread[http--0.0.0.0-3809-6,5,main]
              13:35:10,688 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::addChildThread () action 0:ffffc0a82a4b:3475f402:51c33d15:5d adding Thread[http--0.0.0.0-3809-6,5,main] result = true
              13:35:10,689 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) TransactionReaper::insert ( BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING, 300 )
              13:35:10,690 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) ReaperElement::ReaperElement ( BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING, 300 )
              13:35:10,690 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,691 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,691 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionStatus
              13:35:10,691 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,692 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,692 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:35:10,693 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,693 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--0.0.0.0-3809-6) Subject: Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              
              
              13:35:10,694 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,695 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--0.0.0.0-3809-6) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING >
              13:35:10,696 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,696 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,696 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,697 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,697 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,698 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,698 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,698 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.putResource
              13:35:10,699 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,699 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,699 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,700 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 71bd0d
              Method: getConnection(Subject:
                        Principal: JohnDoe
                        Principal: Roles(members:tisUser)
                        Principal: CallerPrincipal(members)
                        Private Credential: javax.resource.spi.security.PasswordCredential@e3ee18a7
              , null)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: 1bc552c
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl
                Object: 17bd63d
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
                132df48 (NORMAL)
              InUse:
              Statistics:
                ActiveCount: 1
                AvailableCount: 20
                AverageBlockingTime: 0
                AverageCreationTime: 14
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 14
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 14
              13:35:10,706 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) supplying ManagedConnection from pool: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null]
              13:35:10,708 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Got connection from pool tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null] tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING >
              13:35:10,710 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getTransactionKey
              13:35:10,711 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,711 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,712 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.getResource
              13:35:10,712 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,713 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.putResource
              13:35:10,713 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,713 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Using connection from pool tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null] tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING >
              13:35:10,716 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,716 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,717 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING >
              13:35:10,719 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING >
              13:35:10,722 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionSynchronizationRegistryImple.registerInterposedSynchronization
              13:35:10,722 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,723 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Enlisting resource org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=TxSync21393570{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=false}]
              13:35:10,725 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.enlistResource ( LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] )
              13:35:10,726 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,726 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) StateManager::StateManager( 0:0:0:0:1 )
              13:35:10,727 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) AbstractRecord::AbstractRecord (0:0:0:0:1)
              13:35:10,727 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) LastResourceRecord()
              13:35:10,728 TRACE [org.jboss.jca.core.tx.jbossts.LocalXAResourceImpl] (http--0.0.0.0-3809-6) start(< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:5d, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:60, subordinatenodename=null, eis_name=unknown eis name >, 0)
              13:35:10,729 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) RecordList::insert(RecordList: empty) : appending /StateManager/AbstractRecord/LastResourceRecord for 0:0:0:0:1
              13:35:10,729 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Enlisted resource org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:5d, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:60, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync21393570{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=false}]
              13:35:10,733 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffffc0a82a4b:3475f402:51c33d15:5d, node_name=1, branch_uid=0:ffffc0a82a4b:3475f402:51c33d15:60, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync21393570{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}] threadTx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING >
              13:35:10,736 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@17bd63d, connection : org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@18cb4f4, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,739 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) connectionClosed called mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168
              13:35:10,740 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@17bd63d, connection: org.jboss.jca.adapters.jdbc.jdk6.WrappedConnectionJDK6@18cb4f4, key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,741 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) unregisterConnection: 0 handles left
              13:35:10,742 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168
              13:35:10,743 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.getStatus
              13:35:10,743 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.commit
              13:35:10,743 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) TransactionImple.commitAndDisassociate
              13:35:10,744 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.beforeCompletion
              13:35:10,744 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.beforeCompletion
              13:35:10,744 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::End() for action-id 0:ffffc0a82a4b:3475f402:51c33d15:5d
              13:35:10,745 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::onePhaseCommit() for action-id 0:ffffc0a82a4b:3475f402:51c33d15:5d
              13:35:10,745 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) LastResourceRecord::topLevelPrepare() for 0:0:0:0:1
              13:35:10,747 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) LastResourceRecord::topLevelCommit() for 0:0:0:0:1
              13:35:10,747 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:35:10,748 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) afterCompletion(3) isTrackByTx=true for org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null]
              13:35:10,750 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) ManagedConnectionPool: 71bd0d
              Method: returnConnection(132df48, false)
              ManagedConnectionFactory:
                Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory
                Object: 1bc552c
              ConnectionListenerFactory:
                Class: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl
                Object: 17bd63d
              Pool:
                Name: jdbc/myDB
              PoolConfiguration:
                MinSize: 0
                MaxSize: 20
                BlockingTimeout: 30000
                IdleTimeoutMinutes: 30
                BackgroundValidation: false
                BackgroundValidationMillis: 0
                StrictMin: false
                UseFastFail: false
              Available:
              InUse:
                132df48 (NORMAL)
              Statistics:
                ActiveCount: 1
                AvailableCount: 19
                AverageBlockingTime: 0
                AverageCreationTime: 14
                CreatedCount: 1
                DestroyedCount: 0
                MaxCreationTime: 14
                MaxUsedCount: 1
                MaxWaitCount: 0
                MaxWaitTime: 0
                TimedOut: 0
                TotalBlockingTime: 0
                TotalCreationTime: 14
              13:35:10,756 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject] (http--0.0.0.0-3809-6) Returning connection to pool org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710756 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=null]
              13:35:10,758 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) SynchronizationImple.afterCompletion
              13:35:10,758 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::removeChildThread () action 0:ffffc0a82a4b:3475f402:51c33d15:5d removing TSThread:2
              13:35:10,759 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) BasicAction::removeChildThread () action 0:ffffc0a82a4b:3475f402:51c33d15:5d removing TSThread:2 result = true
              13:35:10,760 TRACE [com.arjuna.ats.arjuna] (http--0.0.0.0-3809-6) TransactionReaper::remove ( BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.COMMITTED )
              13:35:10,774 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,775 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,800 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,800 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,800 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,800 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,800 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,800 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,805 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,804 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,803 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,803 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,802 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,808 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-3) BaseTransaction.getStatus
              13:35:10,807 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-1) BaseTransaction.getStatus
              13:35:10,806 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-5) BaseTransaction.getStatus
              13:35:10,806 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-2) BaseTransaction.getStatus
              13:35:10,805 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,809 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,810 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,811 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-4) BaseTransaction.getStatus
              13:35:10,809 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,812 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,810 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,810 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,814 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,813 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,820 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,815 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-3) BaseTransaction.getStatus
              13:35:10,818 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,819 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,814 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,823 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-5) BaseTransaction.getStatus
              13:35:10,823 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-2) BaseTransaction.getStatus
              13:35:10,822 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,822 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,821 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-1) BaseTransaction.getStatus
              13:35:10,824 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,825 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,826 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,826 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,826 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,827 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-4) BaseTransaction.getStatus
              13:35:10,825 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,831 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,830 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,829 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,828 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-3) BaseTransaction.getStatus
              13:35:10,828 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,834 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,833 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-1) BaseTransaction.getStatus
              13:35:10,833 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,831 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,837 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-4) BaseTransaction.getStatus
              13:35:10,836 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,836 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-2) BaseTransaction.getStatus
              13:35:10,835 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,839 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,838 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-5) BaseTransaction.getStatus
              13:35:10,841 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,840 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,842 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-3) BaseTransaction.getStatus
              13:35:10,843 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,845 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,847 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,887 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,887 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,887 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,889 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,888 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,890 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-1) BaseTransaction.getStatus
              13:35:10,889 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,891 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-3) BaseTransaction.getStatus
              13:35:10,892 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,935 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,935 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,936 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,937 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,938 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-1) BaseTransaction.getStatus
              13:35:10,938 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,940 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,940 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,940 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,942 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-3) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,942 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-2) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1efbafa
              13:35:10,940 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,940 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,940 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) old stack for key: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,946 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-4) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,945 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-6) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,944 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-2) BaseTransaction.getStatus
              13:35:10,944 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-3) BaseTransaction.getStatus
              13:35:10,943 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-1) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,949 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.getStatus
              13:35:10,948 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-4) BaseTransaction.getStatus
              13:35:10,947 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (http--0.0.0.0-3809-5) popped object: org.jboss.as.connector.deployers.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@1058662
              13:35:10,950 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-1) BaseTransaction.getStatus
              13:35:10,952 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-5) BaseTransaction.getStatus
              


              • 4. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?
                Adrian Everett Newbie

                I think I see what's going on but it doesn't make any sense.

                 

                If I change the key structure of table_A so it doesn't use an auto-generated identity column, the inserted record will be rolled back whether jta is set to true or false.

                If I change the key structure of table_A back to use an auto-generated identity column, the inserted record will not be rolled back if jta is set to false.

                 

                Next, I went a step further.

                 

                I made two variations of table_A in my database:  one with an auto-generated identity column (table_A1) and one without (table_A2).

                I changed the code to persist one new bean in each table, before throwing the RunTimeException.

                 

                If jta is false:  table_A1 does not get rolled back but table_A2 does.

                If jta is true:  both tables get rolled back.

                 

                Is this a JBoss bug, and if so, should I just forget about ever setting jta to false?  It doesn't look like it buys me anything not to use the default value of true.

                • 5. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?
                  Scott Marlow Master

                  If you look at the log output from 'jta is true', you will see various lines that contain "Enlisting resource":

                  {quote}

                  13:35:10,723 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--0.0.0.0-3809-6) Enlisting resource org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@132df48[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@e11168 connection handles=0 lastUse=1371749710600 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.PoolBySubject@4ec328 pool internal context=SemaphoreArrayListManagedConnectionPool@71bd0d[pool=jdbc/myDB] xaResource=LocalXAResourceImpl@3dfdc4[connectionListener=132df48 connectionManager=17bd63d warned=false currentXid=null] txSync=TxSync21393570{tx=TransactionImple < ac, BasicAction: 0:ffffc0a82a4b:3475f402:51c33d15:5d status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=false}]

                  {quote}

                   

                  When JTA is true, the JCA connection pool manager knows to enlist the connection into the JTA transaction.  When JTA is false, the JCA connection pool manager does not enlist the connection (XAResource) into the JTA transaction and the database work is not done as expected.  You can see that the "Enlisting resource" is missing when JTA is false.

                   

                  Quick answer is that you always should have JTA set to true for applications that use JTA transactions (like yours and most others).

                  • 6. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?
                    Adrian Everett Newbie

                    Ok, thanks for your help.  It sounds like there's no reason not to use JTA.

                    • 7. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?
                      Scott Marlow Master

                      What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?

                      Back to your question, it is also possible to avoid JTA transactions but then you wouldn't want to use container managed (JTA) transactions to control when the database transaction is committed.  In the trace output, you can see:

                       

                      [stdout] (http--0.0.0.0-3809-6) In the JSF Managed bean.  About to call Session Bean method.... 13:35:10,427 DEBUG [org.jboss.as.jpa] (http--0.0.0.0-3809-6) created new TransactionScopedEntityManager for unit name=Aims.ear/myBusinessLogic.jar#myEjb30

                      13:35:10,428 TRACE [com.arjuna.ats.jta] (http--0.0.0.0-3809-6) BaseTransaction.begin

                      Which is the call to your session bean method that started a new JTA transaction (note BaseTransaction.begin). 

                       

                      If you wanted certain parts of your code to avoid JTA transactions (not under the control of container managed transactions started by the EJB container), then would also want to use RESOURCE_LOCAL in the persistence unit definition for that bit of code and only use application managed entity managers (inject a @PersistenceUnit into a EntityManagerFactory and get the entity manager from that).  Then, you could use datasources with jta set to false.