1 2 Previous Next 16 Replies Latest reply on Mar 18, 2014 4:24 PM by yangju
      • 1. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
        smarlow

        Can you show us the "k3_frontend_persistence" persistence.xml again here and if your using environment variables (e.g. referencing a system property in your persistence.xml), show us the environment variable settings also.  Do you have more than one persistence.xml that defines "k3_frontend_persistence"?

         

        Also show us the org.jboss.as.jpa TRACE logging output that mentions "PersistenceUnitMetadataImpl" for "k3_frontend_persistence" (will show the datasource name and other persistence.xml contents).

        • 2. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
          mattil

          The following is persistence.xml in k3-frontend-entities.jar META-INF directory. k3-frontend-entities.jar is an EJB-JAR in k3.ear root directory. I have only one persistence.xml defining "k3_frontend_persistence".

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

          <persistence xmlns="http://java.sun.com/xml/ns/persistence" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="http://java.sun.com/xml/ns/persistence http://java.sun.com/xml/ns/persistence/persistence_2_0.xsd" version="2.0">

              <persistence-unit name="k3_frontend_persistence" transaction-type="JTA">

                  <jta-data-source>java:/FrontendDS</jta-data-source>

                  <shared-cache-mode>ENABLE_SELECTIVE</shared-cache-mode>

                  <properties>

                      <property name="hibernate.dialect" value="com.ecolane.mpk.persistence.util.Mysql5BitBooleanInnoDBDialect"/>

                      <property name="jboss.entity.manager.jndi.name" value="K3FrontendEntityManager"/>

                      <property name="jboss.entity.manager.factory.jndi.name" value="K3FrontendEntityManagerFactory"/>

                      <property name="hibernate.hbm2ddl.auto" value="validate"/>

                     

                      <!-- Infinispan conf -->

                      <property name="hibernate.cache.use_second_level_cache" value="true"/>

                      <property name="hibernate.cache.use_query_cache" value="true"/>

          <!--             <property name="hibernate.cache.region.factory_class" -->

          <!--                 value="org.hibernate.cache.infinispan.JndiInfinispanRegionFactory"/> -->

          <!--             <property name="hibernate.cache.region.factory_class" -->

          <!--                 value="org.hibernate.cache.infinispan.InfinispanRegionFactory"/> -->

          <!--             <property name="hibernate.cache.infinispan.cfg" value="infinispan-configs.xml" />   -->

          <!--             <property name="hibernate.cache.infinispan.cachemanager" value="java:CacheManager/entity"/> -->

                      <property name="hibernate.generate_statistics" value="true"/>

          <!--             <property name="hibernate.cache.use_structured_entries" value="false" /> -->

                      <!-- property name="hibernate.cache.infinispan.statistics" value="true"/-->

          <!--             <property name="hibernate.transaction.manager_lookup_class" -->

          <!--                 value="org.hibernate.transaction.JBossTransactionManagerLookup"/> -->

                  </properties>

              </persistence-unit>

          </persistence>

           

          ESC[0m2013-10-14 05:36:54,708 TRACE [org.jboss.as.jpa] (MSC service thread 1-3:) PersistenceUnitMetadataImpl(version=2.0) [

                  name: k3_frontend_persistence

                  jtaDataSource: java:/FrontendDS

                  nonJtaDataSource: null

                  transactionType: JTA

                  provider: org.hibernate.jpa.HibernatePersistenceProvider

                  classes[

                  ]

                  packages[

                  ]

                  mappingFiles[

                  ]

                  jarFiles[

                  ]

                  validation-mode: AUTO

                  shared-cache-mode: ENABLE_SELECTIVE

                  properties[

                          hibernate.generate_statistics: true

                          jboss.entity.manager.jndi.name: K3FrontendEntityManager

                          hibernate.dialect: com.ecolane.mpk.persistence.util.Mysql5BitBooleanInnoDBDialect

                          hibernate.cache.use_second_level_cache: true

                      jboss.entity.manager.factory.jndi.name: K3FrontendEntityManagerFactory
                      hibernate.cache.use_query_cache: true
                      hibernate.hbm2ddl.auto: validate
              ]]
          • 3. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
            smarlow

            Matti,

             

            Can you confirm that the underlying question that you are seeking help with, is how did the FrontendDS get enlisted into the JTA transaction when you didn't expect it to?

             

            You already have TRACE logging enabled for org.jboss.as.jpa.  You can also enable the transaction manager trace logging via com.arjuna.  To enable the JCA trace logging, set org.jboss.jca.

             

            When you get the failure due to FrontendDS being used unexpectedly, look at the trace output from the error up to the start of the JTA transaction.  You should get some insight into how/why FrontendDS is coming into play.

             

            Scott

            • 4. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
              mattil

              The underlying question that I am seeking help with, is how did the OperationsDS get enlisted into the JTA transaction when you didn't expect it to? OperationalDS was enlisted in the transaction already but FrontendDS should have been enlisted according to the application source code and configuration file but failed. The added logging still indicates a bug to me:

               

              ESC[0m2013-10-16 03:04:43,676 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,676 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,676 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,676 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03

              e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreA

              rrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,676 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.equals

              ESC[0m2013-10-16 03:04:43,676 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@

              75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,677 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,677 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection : org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@3060de36, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,677 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,677 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,677 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,677 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,677 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,677 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,677 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,677 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,677 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) connectionClosed called mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,677 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection: org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@3060de36, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,677 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregisterConnection: 0 handles left

              ESC[0m2013-10-16 03:04:43,678 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@

              3aa06d37

              ESC[0m2013-10-16 03:04:43,678 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,678 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,678 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,678 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,678 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,678 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,678 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0mESC[32m2013-10-16 03:04:43,678 DEBUG [org.jboss.as.jpa] (default task-8:ClientProfileEdit-127.0.0.1-matti) default task-8:[transaction scoped EntityManager]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,680 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,680 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,680 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,681 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,681 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,681 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,681 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,681 TRACE [org.jboss.as.jpa.messages] (default task-8:ClientProfileEdit-127.0.0.1-matti) merge entityClass 'com.ecolane.mpk.persistence.Customer' took 3ms

              ESC[0m2013-10-16 03:04:43,681 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,682 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,682 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,682 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,682 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0mESC[32m2013-10-16 03:04:43,682 DEBUG [org.jboss.as.jpa] (default task-8:ClientProfileEdit-127.0.0.1-matti) default task-8:[transaction scoped EntityManager]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,682 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,682 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,682 TRACE [org.jboss.as.jpa.messages] (default task-8:ClientProfileEdit-127.0.0.1-matti) createNamedQuery name 'findCustomerByCustomerNumber', resultClass 'com.ecolane.mpk.persistence.Customer' took 1ms

              ESC[0m2013-10-16 03:04:43,682 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,682 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,682 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,702 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) Subject: null

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,702 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,702 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (default task-8:ClientProfileEdit-127.0.0.1-matti) Previous connection tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,702 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,703 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,703 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,703 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,703 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.equals

              ESC[0m2013-10-16 03:04:43,703 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Get

              synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true p

              ool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,703 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,703 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection : org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@62eaf381, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,703 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,703 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,703 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,703 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,704 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,704 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,704 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,704 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,705 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,705 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,706 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) connectionClosed called mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,706 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection: org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@62eaf381, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,706 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregisterConnection: 0 handles left

              ESC[0m2013-10-16 03:04:43,706 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,706 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) Subject: null

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,706 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,706 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,707 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (default task-8:ClientProfileEdit-127.0.0.1-matti) Previous connection tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool

              @fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,707 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03

              e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,707 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.equals

              ESC[0m2013-10-16 03:04:43,707 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@

              75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=Semap

              horeArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,707 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,708 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection : org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@4f895f23, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,708 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,708 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,708 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,708 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,708 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,708 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,708 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,708 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,709 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,709 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,709 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,709 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,709 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,709 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,709 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,709 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,709 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) connectionClosed called mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,709 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection: org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@4f895f23, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,709 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregisterConnection: 0 handles left

              ESC[0m2013-10-16 03:04:43,709 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,709 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0mESC[32m2013-10-16 03:04:43,710 DEBUG [org.jboss.as.jpa] (default task-8:ClientProfileEdit-127.0.0.1-matti) default task-8:[transaction scoped EntityManager]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,710 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,712 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) Subject: null

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,712 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,712 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,712 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (default task-8:ClientProfileEdit-127.0.0.1-matti) Previous connection tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,713 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,713 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,713 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,713 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,713 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.equals

              ESC[0m2013-10-16 03:04:43,713 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@

              75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,713 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,713 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection : org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@5a23d331, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,713 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,713 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,713 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,713 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,714 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,714 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) connectionClosed called mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,715 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection: org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@5a23d331, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,715 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregisterConnection: 0 handles left

              ESC[0m2013-10-16 03:04:43,715 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,715 TRACE [org.jboss.as.jpa.messages] (default task-8:ClientProfileEdit-127.0.0.1-matti) persist entityClass 'com.ecolane.mpk.persistence.CustomerProfileEvent' took 5ms

              ESC[0mESC[32m2013-10-16 03:04:43,715 DEBUG [k3.mpk.routing.beans.OrderingToolsBackendBean] (default task-8:ClientProfileEdit-127.0.0.1-matti) customer Naomi M Letzte: changed customerNumber from 12 to 212

              ESC[0m2013-10-16 03:04:43,715 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) Subject: null

              ESC[0m2013-10-16 03:04:43,715 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,715 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,715 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,716 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (default task-8:ClientProfileEdit-127.0.0.1-matti) Previous connection tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxC

              onnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-3

              2166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,716 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,716 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.equals

              ESC[0m2013-10-16 03:04:43,716 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,716 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75

              d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,717 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection : org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@2905106, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,717 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,717 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,717 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,717 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,717 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,717 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,717 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,717 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,717 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) connectionClosed called mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,717 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection: org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@2905106, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,717 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregisterConnection: 0 handles left

              ESC[0m2013-10-16 03:04:43,717 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,718 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) old stack for key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImpleManager.suspend

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::removeChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3ed removing TSThread:10

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::removeChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3ed removing TSThread:10 result = true

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.begin

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) StateManager::StateManager( 2, 0 )

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::BasicAction()

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::Begin() for action-id 0:ffff90cae62c:-32166b75:525e3a44:3f7

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::actionInitialise() for action-id 0:ffff90cae62c:-32166b75:525e3a44:3f7

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) ActionHierarchy::ActionHierarchy(1)

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) ActionHierarchy::add(0:ffff90cae62c:-32166b75:525e3a44:3f7, 1)

              ESC[0m2013-10-16 03:04:43,718 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::addChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3f7 adding Thread[default task-8,5,main]

              ESC[0m2013-10-16 03:04:43,719 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::addChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3f7 adding Thread[default task-8,5,main] result = true

              ESC[0m2013-10-16 03:04:43,719 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionReaper::insert ( BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3f7 status: ActionStatus.RUNNING, 300 )

              ESC[0m2013-10-16 03:04:43,719 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) ReaperElement::ReaperElement ( BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3f7 status: ActionStatus.RUNNING, 300 )

              ESC[0m2013-10-16 03:04:43,720 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) old stack for key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,720 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) popped object: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,720 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) old stack for key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,720 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) popped object: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0mESC[32m2013-10-16 03:04:43,728 DEBUG [org.jboss.as.jpa] (default task-8:ClientProfileEdit-127.0.0.1-matti) created new TransactionScopedEntityManager for unit name=k3.ear/k3-operational-entities.jar#k3_operational_persistence

              ESC[0m2013-10-16 03:04:43,729 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) old stack for key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,729 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) popped object: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,729 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) old stack for key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,729 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) popped object: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,730 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,730 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,730 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.commit

              ESC[0m2013-10-16 03:04:43,730 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.commitAndDisassociate

              ESC[0m2013-10-16 03:04:43,730 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::End() for action-id 0:ffff90cae62c:-32166b75:525e3a44:3f7

              ESC[0m2013-10-16 03:04:43,730 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::removeChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3f7 removing TSThread:10

              ESC[0m2013-10-16 03:04:43,730 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::removeChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3f7 removing TSThread:10 result = true

              ESC[0m2013-10-16 03:04:43,730 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionReaper::remove ( BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3f7 status: ActionStatus.COMMITTED )

              ESC[0m2013-10-16 03:04:43,730 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImpleManager.resume

              ESC[0m2013-10-16 03:04:43,731 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::addChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3ed adding Thread[default task-8,5,main]

              ESC[0m2013-10-16 03:04:43,737 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::addChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3ed adding Thread[default task-8,5,main] result = true

              ESC[0m2013-10-16 03:04:43,737 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) old stack for key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,737 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImpleManager.suspend

              ESC[0m2013-10-16 03:04:43,737 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::removeChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3ed removing TSThread:10

              ESC[0m2013-10-16 03:04:43,737 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::removeChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3ed removing TSThread:10 result = true

              ESC[0m2013-10-16 03:04:43,737 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.begin

              ESC[0m2013-10-16 03:04:43,737 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) StateManager::StateManager( 2, 0 )

              ESC[0m2013-10-16 03:04:43,737 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::BasicAction()

              ESC[0m2013-10-16 03:04:43,737 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::Begin() for action-id 0:ffff90cae62c:-32166b75:525e3a44:3f8

              ESC[0m2013-10-16 03:04:43,737 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::actionInitialise() for action-id 0:ffff90cae62c:-32166b75:525e3a44:3f8

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) ActionHierarchy::ActionHierarchy(1)

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) ActionHierarchy::add(0:ffff90cae62c:-32166b75:525e3a44:3f8, 1)

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::addChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3f8 adding Thread[default task-8,5,main]

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::addChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3f8 adding Thread[default task-8,5,main] result = true

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionReaper::insert ( BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3f8 status: ActionStatus.RUNNING, 300 )

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) ReaperElement::ReaperElement ( BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3f8 status: ActionStatus.RUNNING, 300 )

              ESC[0mESC[32m2013-10-16 03:04:43,738 DEBUG [org.jboss.as.jpa] (default task-8:ClientProfileEdit-127.0.0.1-matti) created new TransactionScopedEntityManager for unit name=k3.ear/k3-frontend-entities.jar#k3_frontend_persistence

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.commit

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.commitAndDisassociate

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::End() for action-id 0:ffff90cae62c:-32166b75:525e3a44:3f8

              ESC[0m2013-10-16 03:04:43,738 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::removeChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3f8 removing TSThread:10

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::removeChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3f8 removing TSThread:10 result = true

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionReaper::remove ( BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3f8 status: ActionStatus.COMMITTED )

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImpleManager.resume

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::addChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3ed adding Thread[default task-8,5,main]

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.arjuna] (default task-8:ClientProfileEdit-127.0.0.1-matti) BasicAction::addChildThread () action 0:ffff90cae62c:-32166b75:525e3a44:3ed adding Thread[default task-8,5,main] result = true

              ESC[0mESC[32m2013-10-16 03:04:43,739 DEBUG [k3.mpk.frontend.beans.FrontendLiaisonBean] (default task-8:ClientProfileEdit-127.0.0.1-matti) synchOneCustomerToFrontend(Customer): starting.

              ESC[0mESC[32m2013-10-16 03:04:43,739 DEBUG [k3.mpk.frontend.beans.FrontendLiaisonBean] (default task-8:ClientProfileEdit-127.0.0.1-matti) getOurFeService: starting.

              ESC[0m2013-10-16 03:04:43,739 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) Subject: null

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,739 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,739 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,740 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,740 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,740 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,740 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (default task-8:ClientProfileEdit-127.0.0.1-matti) Previous connection tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,740 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,740 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,740 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,740 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,740 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.equals

              ESC[0m2013-10-16 03:04:43,740 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,740 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@75d03e26[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 connection handles=0 lastUse=1381907083611 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@fa083da mcp=SemaphoreArrayListManagedConnectionPool@34b3d997[pool=MpkDemoUsDS] xaResource=XAResourceWrapperImpl@7a6253f2[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/OperationalDS] txSync=TransactionSynchronization@644788104{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true cancel=false}] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,740 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) registering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection : org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@203e4518, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,741 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,741 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,741 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,741 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,741 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,741 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,741 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,741 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,741 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) connectionClosed called mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0m2013-10-16 03:04:43,741 TRACE [org.jboss.jca.core.api.connectionmanager.ccm.CachedConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregistering connection from connection manager: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl@5568687c, connection: org.jboss.jca.adapters.jdbc.jdk7.WrappedConnectionJDK7@203e4518, key: org.jboss.as.connector.deployers.ra.processors.CachedConnectionManagerSetupProcessor$CachedConnectionManagerSetupAction@acb3bcf

              ESC[0m2013-10-16 03:04:43,741 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) unregisterConnection: 0 handles left

              ESC[0m2013-10-16 03:04:43,741 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@3aa06d37

              ESC[0mESC[32m2013-10-16 03:04:43,742 DEBUG [k3.mpk.frontend.beans.FrontendLiaisonBean] (default task-8:ClientProfileEdit-127.0.0.1-matti) getOurFeService: this service's hostname is madmax2.ecolane.com

              ESC[0mESC[32m2013-10-16 03:04:43,742 DEBUG [k3.mpk.frontend.beans.FrontendLiaisonBean] (default task-8:ClientProfileEdit-127.0.0.1-matti) getOurFeService: now executing DB query findFeServiceByHostname on table frontend_services.

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionStatus

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getRollbackOnly

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.registerInterposedSynchronization

              ESC[0m2013-10-16 03:04:43,742 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0mESC[32m2013-10-16 03:04:43,743 DEBUG [org.jboss.as.jpa] (default task-8:ClientProfileEdit-127.0.0.1-matti) default task-8:[transaction scoped EntityManager]: created entity manager session TransactionImple < ac, BasicAction: 0:ffff90c

              ae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.registerInterposedSynchronization

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.putResource

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,743 TRACE [org.jboss.as.jpa.messages] (default task-8:ClientProfileEdit-127.0.0.1-matti) createNamedQuery name 'findFeServiceByHostname', resultClass 'com.ecolane.mpk.frontend.persistence.FeService' took 1ms

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,743 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,744 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) Subject: null

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,744 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (default task-8:ClientProfileEdit-127.0.0.1-matti) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,744 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,744 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (default task-8:ClientProfileEdit-127.0.0.1-matti) getConnection(null, WrappedConnectionRequestInfo@37ad0d57[userName=frontend])

              ManagedConnectionPool: 1b9c6122

              Method: getConnection(null, WrappedConnectionRequestInfo@37ad0d57[userName=frontend])

              ManagedConnectionFactory:

                Class: org.jboss.as.connector.subsystems.datasources.AbstractDataSourceService$WildFlyXaMCF

                Object: 206cfd26

              ConnectionListenerFactory:

                Class: org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl

                Object: 535fda6b

              Pool:

                Name: MpkDemoUsFrontendDS

              PoolConfiguration:

                MinSize: 0

                InitialSize: 0

                MaxSize: 20

                BlockingTimeout: 30000

                IdleTimeoutMinutes: 30

                BackgroundValidation: false

                BackgroundValidationMillis: 0

                StrictMin: false

                UseFastFail: false

              Available (1):

                67c678e9 (NORMAL)

              InUse (0):

              Statistics:

                ActiveCount: 1

                AvailableCount: 20

                AverageBlockingTime: 0

                AverageCreationTime: 320

                AverageGetTime: 320

                BlockingFailureCount: 0

                CreatedCount: 1

                DestroyedCount: 0

                IdleCount: 1

                InUseCount: 0

                MaxCreationTime: 320

                MaxGetTime: 320

                MaxUsedCount: 1

                MaxWaitCount: 0

                MaxWaitTime: 0

                TimedOut: 0

                TotalBlockingTime: 0

                TotalCreationTime: 320

                TotalGetTime: 320

                WaitCount: 0

              ESC[0m2013-10-16 03:04:43,744 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (default task-8:ClientProfileEdit-127.0.0.1-matti) supplying ManagedConnection from pool: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@67c678e9[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 connection handles=0 lastUse=1381907020517 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@59a91c11 mcp=SemaphoreArrayListManagedConnectionPool@1b9c6122[pool=MpkDemoUsFrontendDS] xaResource=XAResourceWrapperImpl@6d71ce13[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/FrontendDS] txSync=null]

              ESC[0m2013-10-16 03:04:43,745 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (default task-8:ClientProfileEdit-127.0.0.1-matti) Got connection from pool tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@67c678e9[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 connection handles=0 lastUse=1381907020517 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@59a91c11 mcp=SemaphoreArrayListManagedConnectionPool@1b9c6122[pool=MpkDemoUsFrontendDS] xaResource=XAResourceWrapperImpl@6d71ce13[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/FrontendDS] txSync=null] tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getTransactionKey

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.getResource

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionSynchronizationRegistryImple.putResource

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,745 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (default task-8:ClientProfileEdit-127.0.0.1-matti) Using connection from pool tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@67c678e9[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 connection handles=0 lastUse=1381907020517 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@59a91c11 mcp=SemaphoreArrayListManagedConnectionPool@1b9c6122[pool=MpkDemoUsFrontendDS] xaResource=XAResourceWrapperImpl@6d71ce13[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/FrontendDS] txSync=null] tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) BaseTransaction.getStatus

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,745 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0m2013-10-16 03:04:43,745 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@67c678e9[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 connection handles=0 lastUse=1381907020517 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@59a91c11 mcp=SemaphoreArrayListManagedConnectionPool@1b9c6122[pool=MpkDemoUsFrontendDS] xaResource=XAResourceWrapperImpl@6d71ce13[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/FrontendDS] txSync=null] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,745 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@67c678e9[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 connection handles=0 lastUse=1381907020517 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@59a91c11 mcp=SemaphoreArrayListManagedConnectionPool@1b9c6122[pool=MpkDemoUsFrontendDS] xaResource=XAResourceWrapperImpl@6d71ce13[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/FrontendDS] txSync=null] threadTx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING >

              ESC[0m2013-10-16 03:04:43,746 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (default task-8:ClientProfileEdit-127.0.0.1-matti) Enlisting resource org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@67c678e9[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 connection handles=0 lastUse=1381907020517 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@59a91c11 mcp=SemaphoreArrayListManagedConnectionPool@1b9c6122[pool=MpkDemoUsFrontendDS] xaResource=XAResourceWrapperImpl@6d71ce13[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log jndiName=java:/FrontendDS] txSync=TransactionSynchronization@231266549{tx=TransactionImple < ac, BasicAction: 0:ffff90cae62c:-32166b75:525e3a44:3ed status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=false cancel=false}]

              ESC[0m2013-10-16 03:04:43,746 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.enlistResource ( XAResourceWrapperI

              mpl@6d71ce13[xaResource=org.jboss.jca.adapters.jdbc.xa.XAManagedConnection@172c3e39 pad=false overrideRmValue=null productName=MySQL productVersion=5.5.24-5-log

              jndiName=java:/FrontendDS] )

              ESC[0m2013-10-16 03:04:43,746 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus

              ESC[0m2013-10-16 03:04:43,746 TRACE [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE

              ESC[0mESC[33m2013-10-16 03:04:43,748 WARN  [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) ARJUNA016089: TransactionImple.enlistResource - xa_start  - caught: XAException.XAER_INVAL for < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff90cae62c:-32166b75:525e3a44:3ed, node_name=1, branch_uid=0:ffff90cae62c:-32166b75:525e3a44:3f3, subordinatenodename=null, eis_name=java:/OperationalDS >: com.mysql.jdbc.jdbc2.optional.MysqlXAException: XAER_INVAL: Invalid arguments (or unsupported command)

                      at com.mysql.jdbc.jdbc2.optional.MysqlXAConnection.mapXAExceptionFromSQLException(MysqlXAConnection.java:600) [mysql-connector-java-5.1.26-bin.jar:]

                      at com.mysql.jdbc.jdbc2.optional.MysqlXAConnection.dispatchCommand(MysqlXAConnection.java:583) [mysql-connector-java-5.1.26-bin.jar:]

                      at com.mysql.jdbc.jdbc2.optional.MysqlXAConnection.start(MysqlXAConnection.java:523) [mysql-connector-java-5.1.26-bin.jar:]

                      at org.jboss.jca.adapters.jdbc.xa.XAManagedConnection.start(XAManagedConnection.java:259)

                      at org.jboss.jca.core.tx.jbossts.XAResourceWrapperImpl.start(XAResourceWrapperImpl.java:203)

                      at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.enlistResource(TransactionImple.java:720) [narayana-jts-jacorb-5.0.0.M4.jar:5.0.0.M4 (revision: ${buildNumber})]

                      at com.arjuna.ats.internal.jta.transaction.arjunacore.TransactionImple.enlistResource(TransactionImple.java:402) [narayana-jts-jacorb-5.0.0.M4.jar:5.0.0.M4 (revision: ${buildNumber})]

                  at org.jboss.jca.core.connectionmanager.listener.TxConnectionListener$TransactionSynchronization.enlist(TxConnectionListener.java:787)
                  at org.jboss.jca.core.connectionmanager.listener.TxConnectionListener.enlist(TxConnectionListener.java:296)
                  at org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.managedConnectionReconnected(TxConnectionManagerImpl.java:476)
                  at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.reconnectManagedConnection(AbstractConnectionManager.java:768)
                  at org.jboss.jca.core.connectionmanager.AbstractConnectionManager.allocateConnection(AbstractConnectionManager.java:516)
                  at org.jboss.jca.adapters.jdbc.WrapperDataSource.getConnection(WrapperDataSource.java:142)
                  at org.hibernate.engine.jdbc.connections.internal.DatasourceConnectionProviderImpl.getConnection(DatasourceConnectionProviderImpl.java:138) [hibernate-c

              ore-4.3.0.Beta4.jar:4.3.0.Beta4]

                  at org.hibernate.internal.AbstractSessionImpl$NonContextualJdbcConnectionAccess.obtainConnection(AbstractSessionImpl.java:357) [hibernate-core-4.3.0.Bet

              a4.jar:4.3.0.Beta4]

                  at org.hibernate.engine.jdbc.internal.LogicalConnectionImpl.obtainConnection(LogicalConnectionImpl.java:227) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4

              ]

                  at org.hibernate.engine.jdbc.internal.LogicalConnectionImpl.getConnection(LogicalConnectionImpl.java:170) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]

               

                  at org.hibernate.engine.jdbc.internal.StatementPreparerImpl.connection(StatementPreparerImpl.java:63) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.engine.jdbc.internal.StatementPreparerImpl$5.doPrepare(StatementPreparerImpl.java:164) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.engine.jdbc.internal.StatementPreparerImpl$StatementPreparationTemplate.prepareStatement(StatementPreparerImpl.java:185) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.engine.jdbc.internal.StatementPreparerImpl.prepareQueryStatement(StatementPreparerImpl.java:162) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.loader.Loader.prepareQueryStatement(Loader.java:1867) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1844) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.loader.Loader.executeQueryStatement(Loader.java:1821) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.loader.Loader.doQuery(Loader.java:903) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:348) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.loader.Loader.doList(Loader.java:2534) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]
                  at org.hibernate.loader.Loader.doList(Loader.java:2520) [hibernate-core-

              4.3.0.Beta4.jar:4.3.0.Beta4]

                      at org.hibernate.loader.Loader.listIgnoreQueryCache(Loader.java:2350) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]

                      at org.hibernate.loader.Loader.list(Loader.java:2345) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]

                      at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:496) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]

                      at org.hibernate.hql.internal.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:371) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]

                      at org.hibernate.engine.query.spi.HQLQueryPlan.performList(HQLQueryPlan.java:222) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]

                      at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1270) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]

                      at org.hibernate.internal.QueryImpl.list(QueryImpl.java:101) [hibernate-core-4.3.0.Beta4.jar:4.3.0.Beta4]

                      at org.hibernate.jpa.internal.QueryImpl.getSingleResult(QueryImpl.java:435) [hibernate-entitymanager-4.3.0.Beta4.jar:4.3.0.Beta4]

                      at com.ecolane.mpk.frontend.beans.FrontendLiaisonBean.getOurFeService(FrontendLiaisonBean.java:123) [k3-main.jar:]

                      at com.ecolane.mpk.frontend.beans.FrontendLiaisonBean.synchOneCustomerToFrontend(FrontendLiaisonBean.java:362) [k3-main.jar:]

                      at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_03]

                  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_03]
                  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_03]
                  at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_03]
                  at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45) [wildfly-ee-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
                  at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:406)
                  at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:130) [wildfly-weld-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:138) [wildfly-weld-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
                  at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ejb3.component.invocationmetrics.ExecutionTimeInterceptor.processInvocation(ExecutionTimeInterceptor.java:43) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.jpa.interceptor.SBInvocationInterceptor.processInvocation(SBInvocationInterceptor.java:47) [wildfly-jpa-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:406)
                  at org.jboss.weld.ejb.AbstractEJBRequestScopeActivationInterceptor.aroundInvoke(AbstractEJBRequestScopeActivationInterceptor.java:46) [weld-core-impl-2.1.0.CR1.jar:2013-09-26 16:53]
                  at org.jboss.as.weld.ejb.EjbRequestScopeActivationInterceptor.processInvocation(EjbRequestScopeActivationInterceptor.java:84) [wildfly-weld-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.InitialInterceptor.processInvocation(InitialInterceptor.java:21)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
                  at org.jboss.as.ee.component.interceptors.ComponentDispatcherInterceptor.processInvocation(ComponentDispatcherInterceptor.java:53)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ejb3.component.pool.PooledInstanceInterceptor.processInvocation(PooledInstanceInterceptor.java:51) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ejb3.tx.CMTTxInterceptor.invokeInCallerTx(CMTTxInterceptor.java:251) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.as.ejb3.tx.CMTTxInterceptor.required(CMTTxInterceptor.java:342) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.as.ejb3.tx.CMTTxInterceptor.processInvocation(CMTTxInterceptor.java:239) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ejb3.component.interceptors.CurrentInvocationContextInterceptor.processInvocation(CurrentInvocationContextInterceptor.java:41) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ejb3.component.invocationmetrics.WaitTimeInterceptor.processInvocation(WaitTimeInterceptor.java:43) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ejb3.security.SecurityContextInterceptor.processInvocation(SecurityContextInterceptor.java:95) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ejb3.component.interceptors.ShutDownInterceptorFactory$1.processInvocation(ShutDownInterceptorFactory.java:64) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ejb3.component.interceptors.LoggingInterceptor.processInvocation(LoggingInterceptor.java:59) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ee.component.NamespaceContextInterceptor.processInvocation(NamespaceContextInterceptor.java:50)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ejb3.component.interceptors.AdditionalSetupInterceptor.processInvocation(AdditionalSetupInterceptor.java:55) [wildfly-ejb3-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.ContextClassLoaderInterceptor.processInvocation(ContextClassLoaderInterceptor.java:64)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.ja

              va:309)

                      at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:325)

                      at org.wildfly.security.manager.WildFlySecurityManager.doChecked(WildFlySecurityManager.java:428)

                      at org.jboss.invocation.AccessCheckingInterceptor.processInvocation(AccessCheckingInterceptor.java:61)

                      at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)

                      at org.jboss.invocation.InterceptorContext.run(InterceptorContext.java:325)

                      at org.jboss.invocation.PrivilegedWithCombinerInterceptor.processInvocation(PrivilegedWithCombinerInterceptor.java:80)

                      at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)

                      at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)

                      at org.jboss.as.ee.component.ViewService$View.invoke(ViewService.java:165)

                      at org.jboss.as.ee.component.ViewDescription$1.processInvocation(ViewDescription.java:182)

                      at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)

                  at org.jboss.invocation.ChainedInterceptor.processInvocation(ChainedInterceptor.java:61)
                  at org.jboss.as.ee.component.ProxyInvocationHandler.invoke(ProxyInvocationHandler.java:72)
                  at com.ecolane.mpk.frontend.beans.FrontendLiaisonLocal$$$view99.synchOneCustomerToFrontend(Unknown Source) [k3-main.jar:]
                  at com.ecolane.mpk.apps.beans.ToolBackendBean.synchOneCustomerToFrontend(ToolBackendBean.java:717) [k3-main.jar:]
                  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [rt.jar:1.7.0_03]
                  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [rt.jar:1.7.0_03]
                  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [rt.jar:1.7.0_03]
                  at java.lang.reflect.Method.invoke(Method.java:601) [rt.jar:1.7.0_03]
                  at org.jboss.as.ee.component.ManagedReferenceMethodInterceptorFactory$ManagedReferenceMethodInterceptor.processInvocation(ManagedReferenceMethodInterceptorFactory.java:72)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.as.ee.concurrent.ConcurrentContextInterceptor.processInvocation(ConcurrentContextInterceptor.java:45) [wildfly-ee-8.0.0.Beta1.jar:8.0.0.Beta1]

               

                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
                  at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.InterceptorContext$Invocation.proceed(InterceptorContext.java:406)
                  at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.doMethodInterception(Jsr299BindingsInterceptor.java:130) [wildfly-weld-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.as.weld.ejb.Jsr299BindingsInterceptor.processInvocation(Jsr299BindingsInterceptor.java:138) [wildfly-weld-8.0.0.Beta1.jar:8.0.0.Beta1]
                  at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.java:309)
                  at org.jboss.invocation.WeavedInterceptor.processInvocation(WeavedInterceptor.java:53)
                  at org.jboss.as.ee.component.interceptors.UserInterceptorFactory$1.processInvocation(UserInterceptorFactory.java:63)
                  at org.jboss.invocation.InterceptorContext.proceed(InterceptorContext.ja
              • 5. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                smarlow

                Matti,

                 

                I'm not sure why the enlist is failing, it could be a problem with the JCA datasource configuration.  An example that you can compare to is here http://www.ironjacamar.org/doc/userguide/1.1/en-US/html_single/index.html#ex_datasources_mysql_xa

                 

                Also refer to WildFly8/docs/schema/wildfly-datasources_2_0.xsd which describes the xa-datasource-class and related settings that you are missing in your datasource.

                 

                Let us know if this helps.  I'm not a JCA expert and am guessing that xa-datasource-class will help but we will see.

                 

                Scott

                • 6. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                  mattil

                  I tried to make the FrontendDS configuration similar to http://www.ironjacamar.org/doc/userguide/1.1/en-US/html_single/index.html#ex_datasources_mysql_xa but it did not seem valid but driver element was required. Anyway, I got the same error as before, so your suggestion did not help. xa-datasource-class was defined in driver element and referred to by driver element text.

                              <drivers>
                                  <driver name="h2" module="com.h2database.h2">
                                      <xa-datasource-class>org.h2.jdbcx.JdbcDataSource</xa-datasource-class>
                                  </driver>
                                  <driver name="mysql" module="com.mysql">
                                      <xa-datasource-class>com.mysql.jdbc.jdbc2.optional.MysqlXADataSource</xa-datasource-class>
                                  </driver>
                              </drivers>

                   

                   

                   

                              <xa-datasource jndi-name="java:/FrontendDS" pool-name="MpkDemoUsFrontendDS">
                                  <xa-datasource-property name="ServerName">localhost</xa-datasource-property>
                                  <xa-datasource-property name="DatabaseName">production</xa-datasource-property>
                                  <xa-datasource-class>com.mysql.jdbc.jdbc2.optional.MysqlXADataSource</xa-datasource-class>
                                  <driver>mysql</driver>
                                  <security>
                                      <user-name>frontend</user-name>
                                      <password>pasaranno/</password>
                                  </security>
                                                      <validation>
                                                              <valid-connection-checker
                                      class-name="org.jboss.jca.adapters.jdbc.extensions.mysql.MySQLValidConnectionChecker"></valid-connection-checker>
                                                              <exception-sorter
                                      class-name="org.jboss.jca.adapters.jdbc.extensions.mysql.MySQLExceptionSorter"></exception-sorter>
                                                      </validation>
                              </xa-datasource>
                  • 7. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                    smarlow

                    Does the MySQL server logs show any error messages around the time that the "com.mysql.jdbc.jdbc2.optional.MysqlXAException: XAER_INVAL: Invalid arguments (or unsupported command)" occurs?

                    • 8. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                      mattil

                      No, the MySQL server logs (syslog) do not show any error messages around the time that the "com.mysql.jdbc.jdbc2.optional.MysqlXAException: XAER_INVAL: Invalid arguments (or unsupported command)" occurs?

                      • 9. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                        smarlow

                        Have you looked for updates to the MySQL  JDBC driver that you are using? 

                        • 10. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                          mattil

                          I seem to be using the latest MySQL JDBC driver available from https://dev.mysql.com/downloads/connector/j/#downloads - 5.1.26.

                          • 11. Re: Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                            smarlow

                            It looks like that is a warning (from com.arjuna.ats.jta) but I agree it should be looked into further (probably more on the MySQL connector/server side is my guess but I don't know for sure).  Do you have the source for the MySQL connector?  Could you try patching the MySQL connector to show more information.  You could also debug into the MySQL connector (it is just Java code) and explore why the error is thrown (and then create a patch to show more information). 

                             

                            WARN  [com.arjuna.ats.jta] (default task-8:ClientProfileEdit-127.0.0.1-matti) ARJUNA016089: TransactionImple.enlistResource - xa_start  - caught: XAException.XAER_INVAL for < formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff90cae62c:-32166b75:525e3a44:3ed, node_name=1, branch_uid=0:ffff90cae62c:-32166b75:525e3a44:3f3, subordinatenodename=null, eis_name=java:/OperationalDS >: com.mysql.jdbc.jdbc2.optional.MysqlXAException: XAER_INVAL: Invalid arguments (or unsupported command)

                            • 12. Re: Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                              smarlow

                              Actually, this looks like its more than a warning (TransactionImple.enlistResource did not enlist the MySQL connection into the JTA transaction).

                               

                              Is the OperationsDS also configured the same (to use XA?)

                              • 13. Re: Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                                mattil

                                Yes, the warning is followed by errors. OperationalDS is configured to use XA, too, and is generally similarly configured.

                                • 14. Re: Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
                                  smarlow

                                  http://bugs.mysql.com/bug.php?id=17343 is not new but sounds similar.

                                   

                                  You can probably enable TRACE logging on the MySQL server side to get more information as to what is happening.  If MySQL is not allowing the second connection to be enlisted, you could either help fix the mySQL server by submitting a patch that fixes that.  It might be a big change otherwise it would of already been done, but you will have to decide for yourself (probably after downloading the latest MySQL source and building, verifying that a smaller test case still has the same issue).  Or you could avoid enlisting the second connection into the transaction (via application changes to dance around this).

                                  1 2 Previous Next