1 2 Previous Next 16 Replies Latest reply on Mar 18, 2014 4:24 PM by Richard Yang

    Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction

    Matti Linnanvuori Newbie

      I made an bug report about Wildfly 8 not being able to handle a NamedQuery with a second EntityManager connected to a new data source within a transaction: [WFLY-2280] Wrong XA Datasource selected - JBoss Issue Tracker

      I think other people have found a similar same bug unanswered JBoss AS 7.1.0.Beta1 - ARJUNA12140: Adding multiple last resources is disallowed

      JBoss 7, two databases and a single transaction http://blog.wombatsoftware.de/2012/12/jboss-7-two-databases-and-single.html

        • 1. Re: Wildfly could not handle a NamedQuery with two EntityManagers and two data sources in a transaction
          Scott Marlow Master

          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
            Matti Linnanvuori Newbie

            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
              Scott Marlow Master

              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
                Matti Linnanvuori Newbie

                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
                  Scott Marlow Master

                  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
                    Matti Linnanvuori Newbie

                    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
                      Scott Marlow Master

                      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
                        Matti Linnanvuori Newbie

                        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
                          Scott Marlow Master

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

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

                            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
                              Scott Marlow Master

                              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
                                Matti Linnanvuori Newbie

                                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
                                  Scott Marlow Master

                                  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