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

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

kidvid Jun 20, 2013 12:12 PM

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?

smarlow Jun 20, 2013 12:17 PM (in response to kidvid)

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.
Actions
2. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?

kidvid Jun 20, 2013 12:18 PM (in response to kidvid)

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;
Actions

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

kidvid Jun 20, 2013 2:23 PM (in response to kidvid)

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?

kidvid Jun 20, 2013 3:31 PM (in response to kidvid)

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.
Actions
5. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?

smarlow Jun 20, 2013 4:11 PM (in response to kidvid)

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).
Actions
6. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?

kidvid Jun 20, 2013 4:31 PM (in response to smarlow)

Ok, thanks for your help. It sounds like there's no reason not to use JTA.
Actions
7. Re: What are the downsides of setting jta="true" and why would I need it for rollbacks to work with one DB?

smarlow Jun 20, 2013 5:59 PM (in response to kidvid)

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.
Actions

Go to original post