1 2 Previous Next 19 Replies Latest reply on Apr 13, 2012 5:32 AM by jaikiran

    Problem with transactions...

    japochino1

      Hi

       

      I'm having problems with transactions.

       

      My persistence.xml:

       

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

      <persistence version="2.0"

          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">

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

              <provider>org.hibernate.ejb.HibernatePersistence</provider>

      ....

       

      So transaction-type="JTA" indicates that the default manager for transactions will be the container.

       

      My bean:

       

      @Stateless

      @LocalBean

      public class ServiciosSinEstado implements ServiciosSinEstadoRemote,

              ServiciosSinEstadoLocal {

       

          @PersistenceContext(unitName = "bdJPA")

          EntityManager em;

       

          @Resource

          private EJBContext context;

       

      ...

       

      I don't write @TransactionManagement(value=TransactionManagementType.CONTAINER) because the transaction-type="JTA" declaration already indicates that (otherwise I have tried writing it too)

       

      My bean method:

       

      @Override

      @TransactionAttribute(value = TransactionAttributeType.REQUIRED) // although by default

      public boolean validarPeticionAlta(PeticionAlta peticionAlta) {

      ...

      // I create some entities for persist and prepare the entity peticionAlta (the param of the method) for modify

      // I call the merge method of the entity manager for all the entities I have created and for the entity peticionAlta. In this way

       

              try {

                  em.merge(entity);

              } catch (Exception e) {          

                   context.setRollbackOnly();

                  return false;

              }

       

      ...

       

             try {

                  peticionAlta.setProp1(param1);

                  peticionAlta.setProp2(param2);

                  em.merge(peticionAlta);

              } catch (Exception e) {

                  context.setRollbackOnly();

                  return false;

              }

       

       

      I use context.setRollbackOnly(); when something go wrong to tell the container to do the rollback.

       

      Willfully I write this to force the rollback:

       

      if(true){

           context.setRollbackOnly();

           return false;

      }

       

      At the end of the method:

       

      return true;

       

       

       

       

      The result?

       

      The container only do the correct rollback for the peticionAlta entity. Other entities have been persisted to the database!?

       

      I have tried CMT and BMT and the result is the same.

       

      Help please!

       

      Thanks

        • 1. Re: Problem with transactions...
          smarlow

          Can you show us the contents of your datasource definition.

           

          You can enable TRACE logging to get a better idea of what is going on (output will be in as7/standalone/log/server.log).  Here is a description of how to do that.  I would enable TRACE for org.jboss.as.jpa, org.jboss.jpa, org.hibernate.SQL, com.arjuna.

          • 2. Re: Problem with transactions...
            japochino1

            In my standalone.xml:

             

            <datasource jndi-name="[jndi_name]" pool-name="[pool_ame]" enabled="true" jta="true" use-java-context="true" use-ccm="true">

                                <connection-url>

                                    jdbc:mysql://[url]

                                </connection-url>

                                <connection-property name="zeroDateTimeBehavior">

                                    convertToNull

                                </connection-property>

                                <driver>

                                    mysql-connector-java-5.1.17-bin.jar

                                </driver>

                                <security>

                                    <user-name>

                                        user

                                    </user-name>

                                    <password>

                                        password

                                    </password>

                                </security>

                            </datasource>

            • 3. Re: Problem with transactions...
              smarlow

              Datasource looks good (I thought jta was going to be set wrong but its not).  Can you recreate with the trace logging enabled that I mentioned above and attach server.log here.

              • 4. Re: Problem with transactions...
                japochino1

                When I enable TRACE in:

                 

                <subsystem xmlns="urn:jboss:domain:logging:1.1">

                            <console-handler name="CONSOLE" autoflush="true">

                                <level name="TRACE"/>

                ...

                 

                The server doesn't start well. It repeats always this lines in the console view (I'm using Eclipse) and never ends:

                 

                18:26:00,912 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 1) Scan complete

                18:26:05,913 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 2) Scanning directory D:\jboss-as-web-7.0.2.Final\standalone\deployments for deployment content changes

                18:26:05,913 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 2) Executing "read-children-names" undefined

                18:26:05,920 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 2) Prepared response is {

                    "outcome" => undefined,

                    "result" => [

                        "labanda.ear",

                        "mysql-connector-java-5.1.17-bin.jar"

                    ]

                }

                18:26:05,922 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 2) Scan complete

                18:26:10,923 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 2) Scanning directory D:\jboss-as-web-7.0.2.Final\standalone\deployments for deployment content changes

                18:26:10,923 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 2) Executing "read-children-names" undefined

                18:26:10,934 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 2) Prepared response is {

                    "outcome" => undefined,

                    "result" => [

                        "labanda.ear",

                        "mysql-connector-java-5.1.17-bin.jar"

                    ]

                }

                18:26:10,938 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 2) Scan complete

                18:26:15,939 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 1) Scanning directory D:\jboss-as-web-7.0.2.Final\standalone\deployments for deployment content changes

                18:26:15,939 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 1) Executing "read-children-names" undefined

                18:26:15,944 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 1) Prepared response is {

                    "outcome" => undefined,

                    "result" => [

                        "labanda.ear",

                        "mysql-connector-java-5.1.17-bin.jar"

                    ]

                }

                18:26:15,946 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 1) Scan complete

                18:26:20,946 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 2) Scanning directory D:\jboss-as-web-7.0.2.Final\standalone\deployments for deployment content changes

                18:26:20,946 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 2) Executing "read-children-names" undefined

                18:26:20,956 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 2) Prepared response is {

                    "outcome" => undefined,

                    "result" => [

                        "labanda.ear",

                        "mysql-connector-java-5.1.17-bin.jar"

                    ]

                }

                18:26:20,960 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 2) Scan complete

                18:26:25,961 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 2) Scanning directory D:\jboss-as-web-7.0.2.Final\standalone\deployments for deployment content changes

                18:26:25,961 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 2) Executing "read-children-names" undefined

                18:26:25,971 TRACE [org.jboss.as.controller] (DeploymentScanner-threads - 2) Prepared response is {

                    "outcome" => undefined,

                    "result" => [

                        "labanda.ear",

                        "mysql-connector-java-5.1.17-bin.jar"

                    ]

                }

                18:26:25,973 TRACE [org.jboss.as.deployment] (DeploymentScanner-threads - 2) Scan complete

                 

                 

                Finally I got:

                Captura.PNG

                • 5. Re: Problem with transactions...
                  smarlow

                  Come back to the idea of enabling trace logging later.  Instead, try upgrading to the latest AS7 (version 7.1.1) and see if you still have the same issue.  If yes, then enable trace logging.

                  • 6. Re: Problem with transactions...
                    japochino1

                    How can I upgrade my JBoss AS 7.0.2 Final to JBoss AS 7.1.1?

                     

                    Or do I have to make a fully install of the latest version?

                     

                    thanks!

                    • 7. Re: Problem with transactions...
                      smarlow

                      Try a full install of the latest version. 

                       

                      Scott

                      • 8. Re: Problem with transactions...
                        japochino1

                        JBoss AS 7.1.1 installed bt the same problem.

                         

                        I see now a Use JTA? check in connection tab, I check it, the problem still occurs.

                         

                        Any idea?

                        • 9. Re: Problem with transactions...
                          smarlow

                          Try to enable TRACE logging again, as described here.  You should enable TRACE for org.jboss.as.jpa, org.jboss.jpa, org.hibernate.SQL, com.arjuna.

                           

                          Then recreate the problem, the server.log output should contain details as to what happened exactly at each point. 

                          • 10. Re: Problem with transactions...
                            japochino1

                            I have configured in my connection:

                             

                            Transaction Isolation: TRANSACTION_READ_COMMITTED (I don' t know exactly what it does but now I understand that it's not the problem)

                             

                            I have enable TRACE for org.jboss.as.jpa, org.jboss.jpa, org.hibernate.SQL and com.arjuna

                             

                            and this is the log:

                             

                            14:13:29,852 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Releasing JDBC connection

                            14:13:29,853 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) connectionClosed called mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1aefd7b

                            14:13:29,854 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) unregisterConnection: 0 handles left

                            14:13:29,855 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1aefd7b

                            14:13:29,857 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Released JDBC connection

                            14:13:29,858 TRACE [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Total objects hydrated: 1

                            14:13:29,859 DEBUG [org.hibernate.engine.internal.TwoPhaseLoad] (http--127.0.0.1-8080-1) Resolving associations for [com.alteda.labanda.entidades.PeticionAlta#249275]

                            14:13:29,860 DEBUG [org.hibernate.engine.internal.TwoPhaseLoad] (http--127.0.0.1-8080-1) Done materializing entity [com.alteda.labanda.entidades.PeticionAlta#249275]

                            14:13:29,861 DEBUG [org.hibernate.engine.internal.StatefulPersistenceContext] (http--127.0.0.1-8080-1) Initializing non-lazy collections

                            14:13:29,862 DEBUG [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Done entity load

                            14:13:29,863 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) BaseTransaction.getStatus

                            14:13:29,863 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) BaseTransaction.getStatus

                            14:13:29,863 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                            14:13:29,863 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                            14:13:29,864 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class org.hibernate.tuple.ElementWrapper from Module "org.hibernate:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,866 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class org.hibernate.tuple.ElementWrapper from Module "org.hibernate:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,867 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class org.hibernate.tuple.ElementWrapper locally from Module "org.hibernate:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,869 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class org.hibernate.tuple.ElementWrapper in Module "org.hibernate:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,871 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class org.hibernate.tuple.ElementWrapper in Module "org.hibernate:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,874 TRACE [org.jboss.as.jpa] (http--127.0.0.1-8080-1) merge entityClass 'com.alteda.labanda.entidades.PeticionAlta' took 458ms

                            14:13:29,876 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.alteda.utilidades.LdapConexion from Module "deployment.labanda.ear.labandaEJB.jar:main" from Service Module Loader

                            14:13:29,877 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.alteda.utilidades.LdapConexion from Module "deployment.labanda.ear:main" from Service Module Loader

                            14:13:29,878 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.alteda.utilidades.LdapConexion locally from Module "deployment.labanda.ear:main" from Service Module Loader

                            14:13:29,879 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.alteda.utilidades.LdapConexion in Module "deployment.labanda.ear:main" from Service Module Loader

                            14:13:29,881 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.alteda.utilidades.LdapConexion in Module "deployment.labanda.ear:main" from Service Module Loader

                            14:13:29,882 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class javax.naming.NamingException from Module "deployment.labanda.ear:main" from Service Module Loader

                            14:13:29,884 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class javax.naming.directory.Attribute from Module "deployment.labanda.ear:main" from Service Module Loader

                            14:13:29,885 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class javax.naming.directory.DirContext from Module "deployment.labanda.ear:main" from Service Module Loader

                            14:13:29,886 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class javax.naming.directory.InitialDirContext from Module "deployment.labanda.ear:main" from Service Module Loader

                            14:13:29,889 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to find all resources jndi.properties in Module "deployment.labanda.ear.labandaEJB.jar:main" from Service Module Loader

                            14:13:29,891 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class org.jboss.as.naming.InitialContextFactoryBuilder$1 from Module "org.jboss.as.naming:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,892 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class org.jboss.as.naming.InitialContextFactoryBuilder$1 from Module "org.jboss.as.naming:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,894 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class org.jboss.as.naming.InitialContextFactoryBuilder$1 locally from Module "org.jboss.as.naming:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,896 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class org.jboss.as.naming.InitialContextFactoryBuilder$1 in Module "org.jboss.as.naming:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,898 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class org.jboss.as.naming.InitialContextFactoryBuilder$1 in Module "org.jboss.as.naming:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:29,900 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.sun.jndi.ldap.LdapCtxFactory from Module "deployment.labanda.ear.labandaEJB.jar:main" from Service Module Loader

                            14:13:30,003 INFO  [stdout] (http--127.0.0.1-8080-1) Conectado! Contexto establecido

                             

                            14:13:30,005 INFO  [stdout] (http--127.0.0.1-8080-1) Conexión cerrada

                             

                            14:13:30,006 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class org.jboss.as.ejb3.component.stateful.CurrentSynchronizationCallback from Module "org.jboss.as.ejb3:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,008 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class org.jboss.as.ejb3.component.stateful.CurrentSynchronizationCallback from Module "org.jboss.as.ejb3:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,010 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class org.jboss.as.ejb3.component.stateful.CurrentSynchronizationCallback locally from Module "org.jboss.as.ejb3:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,012 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class org.jboss.as.ejb3.component.stateful.CurrentSynchronizationCallback in Module "org.jboss.as.ejb3:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,014 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class org.jboss.as.ejb3.component.stateful.CurrentSynchronizationCallback in Module "org.jboss.as.ejb3:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,016 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) BaseTransaction.setRollbackOnly

                            14:13:30,016 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) BaseTransaction.setRollbackOnly

                            14:13:30,016 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.setRollbackOnly

                            14:13:30,016 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.setRollbackOnly

                            14:13:30,017 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::preventCommit( BasicAction: 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a status: ActionStatus.RUNNING)

                            14:13:30,017 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::preventCommit( BasicAction: 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a status: ActionStatus.RUNNING)

                            14:13:30,019 TRACE [org.jboss.as.ejb3] (http--127.0.0.1-8080-1) 0/20 Free instance: org.jboss.as.ejb3.pool.strictmax.StrictMaxPool@8202f4

                            14:13:30,020 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                            14:13:30,020 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                            14:13:30,020 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) BaseTransaction.rollback

                            14:13:30,020 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) BaseTransaction.rollback

                            14:13:30,021 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.rollbackAndDisassociate

                            14:13:30,021 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.rollbackAndDisassociate

                            14:13:30,022 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::Abort() for action-id 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a

                            14:13:30,022 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::Abort() for action-id 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a

                            14:13:30,024 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::doAbort (LastResourceRecord(XAOnePhaseResource(LocalXAResourceImpl@183d08c[connectionListener=19a1843 connectionManager=2db3da warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a76a4a2:-4bc54203:4f86c60d:2a, node_name=1, branch_uid=0:ffff0a76a4a2:-4bc54203:4f86c60d:2e, subordinatenodename=null, eis_name=unknown eis name >])))

                            14:13:30,024 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::doAbort (LastResourceRecord(XAOnePhaseResource(LocalXAResourceImpl@183d08c[connectionListener=19a1843 connectionManager=2db3da warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a76a4a2:-4bc54203:4f86c60d:2a, node_name=1, branch_uid=0:ffff0a76a4a2:-4bc54203:4f86c60d:2e, subordinatenodename=null, eis_name=unknown eis name >])))

                            14:13:30,028 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) LastResourceRecord::topLevelAbort() for 0:0:0:0:1

                            14:13:30,028 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) LastResourceRecord::topLevelAbort() for 0:0:0:0:1

                            14:13:30,074 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.SQLError from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,075 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.SQLError from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,076 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.SQLError locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,078 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.SQLError in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,081 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.SQLError in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,083 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.NotImplemented from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,084 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.NotImplemented from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,085 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.NotImplemented locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,087 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.NotImplemented in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,088 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.NotImplemented in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,090 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.exceptions.MySQLTransientConnectionException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,091 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.exceptions.MySQLTransientConnectionException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,093 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.exceptions.MySQLTransientConnectionException locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,095 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.exceptions.MySQLTransientConnectionException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,096 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.exceptions.MySQLTransientConnectionException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,098 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,100 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,101 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,103 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,105 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.exceptions.MySQLNonTransientConnectionException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,106 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.exceptions.MySQLDataException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,108 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.exceptions.MySQLDataException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,109 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.exceptions.MySQLDataException locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,111 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.exceptions.MySQLDataException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,112 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.exceptions.MySQLDataException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,114 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.exceptions.MySQLIntegrityConstraintViolationException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,116 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.exceptions.MySQLIntegrityConstraintViolationException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,117 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.exceptions.MySQLIntegrityConstraintViolationException locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,119 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.exceptions.MySQLIntegrityConstraintViolationException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,121 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.exceptions.MySQLIntegrityConstraintViolationException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,123 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.exceptions.MySQLSyntaxErrorException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,124 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.exceptions.MySQLSyntaxErrorException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,126 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.exceptions.MySQLSyntaxErrorException locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,128 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.exceptions.MySQLSyntaxErrorException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,129 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.exceptions.MySQLSyntaxErrorException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,131 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.exceptions.MySQLTransactionRollbackException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,132 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.exceptions.MySQLTransactionRollbackException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,134 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.exceptions.MySQLTransactionRollbackException locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,136 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.exceptions.MySQLTransactionRollbackException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,138 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.exceptions.DeadlockTimeoutRollbackMarker from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,143 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.exceptions.DeadlockTimeoutRollbackMarker from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,144 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.exceptions.DeadlockTimeoutRollbackMarker locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,146 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.exceptions.DeadlockTimeoutRollbackMarker in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,148 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.exceptions.DeadlockTimeoutRollbackMarker in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,149 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.exceptions.MySQLTransactionRollbackException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,152 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.mysql.jdbc.exceptions.jdbc4.CommunicationsException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,154 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.mysql.jdbc.exceptions.jdbc4.CommunicationsException from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,156 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.mysql.jdbc.exceptions.jdbc4.CommunicationsException locally from Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,158 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.mysql.jdbc.exceptions.jdbc4.CommunicationsException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,159 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define package com.mysql.jdbc.exceptions.jdbc4 in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,161 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined package com.mysql.jdbc.exceptions.jdbc4 in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,163 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.mysql.jdbc.exceptions.jdbc4.CommunicationsException in Module "deployment.mysql-connector-java-5.1.17-bin.jar:main" from Service Module Loader

                            14:13:30,211 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class com.arjuna.ats.arjuna.coordinator.HeuristicNotification from Module "org.jboss.jts:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,212 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class com.arjuna.ats.arjuna.coordinator.HeuristicNotification from Module "org.jboss.jts:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,214 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class com.arjuna.ats.arjuna.coordinator.HeuristicNotification locally from Module "org.jboss.jts:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,216 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class com.arjuna.ats.arjuna.coordinator.HeuristicNotification in Module "org.jboss.jts:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,217 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class com.arjuna.ats.arjuna.coordinator.HeuristicNotification in Module "org.jboss.jts:main" from local module loader @11e1e67 (roots: D:\jboss-as-7.1.1.Final\modules)

                            14:13:30,219 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) SynchronizationImple.afterCompletion

                            14:13:30,219 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) SynchronizationImple.afterCompletion

                            14:13:30,220 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) afterCompletion(4) isTrackByTx=true for org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@19a1843[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1aefd7b connection handles=0 lastUse=1334232798651 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@1cf2ef6 pool internal context=SemaphoreArrayListManagedConnectionPool@888d65[pool=java:/interfaz_sadesi] xaResource=LocalXAResourceImpl@183d08c[connectionListener=19a1843 connectionManager=2db3da warned=false currentXid=null] txSync=null]

                            14:13:30,225 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (http--127.0.0.1-8080-1) ManagedConnectionPool: 888d65

                            Method: returnConnection(19a1843, false)

                            ManagedConnectionFactory:

                              Class: org.jboss.jca.adapters.jdbc.local.LocalManagedConnectionFactory

                              Object: 164da25

                            ConnectionListenerFactory:

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

                              Object: 2db3da

                            Pool:

                              Name: java:/interfaz_sadesi

                            PoolConfiguration:

                              MinSize: 0

                              MaxSize: 20

                              BlockingTimeout: 30000

                              IdleTimeoutMinutes: 30

                              BackgroundValidation: false

                              BackgroundValidationMillis: 0

                              StrictMin: false

                              UseFastFail: false

                            Available:

                            InUse:

                              19a1843 (NORMAL)

                            Statistics:

                              ActiveCount: 1

                              AvailableCount: 19

                              AverageBlockingTime: 0

                              AverageCreationTime: 2722

                              CreatedCount: 1

                              DestroyedCount: 0

                              MaxCreationTime: 2722

                              MaxUsedCount: 1

                              MaxWaitCount: 0

                              MaxWaitTime: 0

                              TimedOut: 0

                              TotalBlockingTime: 0

                              TotalCreationTime: 2722

                            14:13:30,232 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (http--127.0.0.1-8080-1) Returning connection to pool org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@19a1843[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@1aefd7b connection handles=0 lastUse=1334232810232 trackByTx=false pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@1cf2ef6 pool internal context=SemaphoreArrayListManagedConnectionPool@888d65[pool=java:/interfaz_sadesi] xaResource=LocalXAResourceImpl@183d08c[connectionListener=19a1843 connectionManager=2db3da warned=false currentXid=null] txSync=null]

                            14:13:30,237 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.equals

                            14:13:30,237 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.equals

                            14:13:30,237 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.equals

                            14:13:30,237 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.equals

                            14:13:30,238 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) SynchronizationImple.afterCompletion

                            14:13:30,238 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) SynchronizationImple.afterCompletion

                            14:13:30,239 DEBUG [org.jboss.as.jpa] (http--127.0.0.1-8080-1) http--127.0.0.1-8080-1:[transaction scoped EntityManager]: closing entity managersession

                            14:13:30,240 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) BaseTransaction.getStatus

                            14:13:30,240 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) BaseTransaction.getStatus

                            14:13:30,240 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                            14:13:30,240 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                            14:13:30,241 TRACE [org.hibernate.internal.SessionImpl] (http--127.0.0.1-8080-1) Closing session

                            14:13:30,242 TRACE [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Closing logical connection

                            14:13:30,243 TRACE [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of connection method [close]

                            14:13:30,244 TRACE [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler] (http--127.0.0.1-8080-1) Invalidating connection handle

                            14:13:30,245 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Closing JDBC container [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl@c24cb3]

                            14:13:30,247 TRACE [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Logical connection closed

                            14:13:30,247 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) SynchronizationImple.afterCompletion

                            14:13:30,247 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) SynchronizationImple.afterCompletion

                            14:13:30,248 TRACE [org.hibernate.engine.transaction.synchronization.internal.RegisteredSynchronization] (http--127.0.0.1-8080-1) JTA sync : afterCompletion(4)

                            14:13:30,249 TRACE [org.hibernate.engine.transaction.synchronization.internal.SynchronizationCallbackCoordinatorImpl] (http--127.0.0.1-8080-1) Transaction after completion callback [status=4]

                            14:13:30,251 TRACE [org.hibernate.ejb.AbstractEntityManagerImpl] (http--127.0.0.1-8080-1) Session was closed; nothing to do

                            14:13:30,252 TRACE [org.hibernate.engine.transaction.internal.TransactionCoordinatorImpl] (http--127.0.0.1-8080-1) after transaction completion

                            14:13:30,253 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Aggressively releasing JDBC connection

                            14:13:30,254 TRACE [org.hibernate.internal.SessionImpl] (http--127.0.0.1-8080-1) after transaction completion

                            14:13:30,254 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::removeChildThread () action 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a removing TSThread:1

                            14:13:30,254 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::removeChildThread () action 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a removing TSThread:1

                            14:13:30,256 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::removeChildThread () action 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a removing TSThread:1 result = true

                            14:13:30,256 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) BasicAction::removeChildThread () action 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a removing TSThread:1 result = true

                            14:13:30,257 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) TransactionReaper::remove ( BasicAction: 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a status: ActionStatus.ABORTED )

                            14:13:30,257 TRACE [com.arjuna.ats.arjuna] (http--127.0.0.1-8080-1) TransactionReaper::remove ( BasicAction: 0:ffff0a76a4a2:-4bc54203:4f86c60d:2a status: ActionStatus.ABORTED )

                            • 11. Re: Problem with transactions...
                              smarlow

                              You didn't include enough, try attaching the server.log or more of it.  To attach a file, click on "use advanced editor" and then use  "attach file" button on bottom of screen.

                               

                              You also seemed to enable too much trace but that is okay.

                              • 12. Re: Problem with transactions...
                                japochino1

                                Here is a more wide extract of the server.log

                                 

                                Thanks

                                • 13. Re: Problem with transactions...
                                  smarlow

                                  My initial concern, was that you found a bug in AS7.  From looking at the TRACE information in the server.log, its more that you just didn't understand why the other two entities didn't get rolled back.  Its more obvious after reading the server.log (the other two entities are already saved in the database from an earlier JTA transaction).

                                   

                                  Look for keyword 'merge', there are three of them.  They reflect the calls in your application to em.merge(...).  Look at the server.log from the point of the first 'merge'.

                                  16:50:05,459 TRACE [org.jboss.as.jpa] (http--127.0.0.1-8080-1) merge entityClass 'com.alteda.labanda.entidades.Socio' took 789ms

                                  16:50:05,709 TRACE [org.jboss.as.jpa] (http--127.0.0.1-8080-1) merge entityClass 'com.alteda.labanda.entidades.Log' took 249ms

                                  16:50:06,151 TRACE [org.jboss.as.jpa] (http--127.0.0.1-8080-1) merge entityClass 'com.alteda.labanda.entidades.PeticionAlta' took 441ms

                                   

                                  I see one insert after timestamp 16:50:05:459:

                                   

                                  16:50:05,541 DEBUG [org.hibernate.SQL] (http--127.0.0.1-8080-1) insert into log (accion, apellido1, apellido2, carnet_socio, cp, domicilio, email, escalera, fecha, fecha_alta, fecha_nacimiento, localidad, motivo, nombre, nombre_usuario, numero, numero_socio, piso, provincia, puerta, sexo, sigla, telefono, usuario_alta) values (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)

                                   

                                  Hibernate releases the db connection (which should be cached by the cached connection manager for the current JTA transaction):

                                   

                                  16:50:05,704 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Releasing JDBC connection

                                  16:50:05,705 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) connectionClosed called mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@fbde8c

                                  16:50:05,706 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) unregisterConnection: 0 handles left

                                  16:50:05,707 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@fbde8c

                                  16:50:05,708 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Released JDBC connection

                                   

                                  For the next entity 'merge', the entity is already saved and its loaded from the database (that is why rollback wouldn't do what you thought).  This is reflected in the next part of the server.log:

                                   

                                   

                                  16:50:05,709 TRACE [org.jboss.as.jpa] (http--127.0.0.1-8080-1) merge entityClass 'com.alteda.labanda.entidades.Log' took 249ms

                                  16:50:05,710 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                                  16:50:05,711 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                                  16:50:05,712 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionSynchronizationRegistryImple.getResource

                                  16:50:05,713 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                                  16:50:05,713 DEBUG [org.jboss.as.jpa] (http--127.0.0.1-8080-1) http--127.0.0.1-8080-1:[transaction scoped EntityManager]: reuse entity manager session already in tx TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING >

                                  16:50:05,715 TRACE [org.hibernate.engine.spi.IdentifierValue] (http--127.0.0.1-8080-1) ID unsaved-value: null

                                  16:50:05,716 TRACE [org.hibernate.event.internal.AbstractSaveEventListener] (http--127.0.0.1-8080-1) Detached instance of: com.alteda.labanda.entidades.PeticionAlta

                                  16:50:05,717 TRACE [org.hibernate.event.internal.DefaultMergeEventListener] (http--127.0.0.1-8080-1) Merging detached instance

                                  16:50:05,718 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class org.hibernate.event.spi.LoadEvent from Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,720 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class org.hibernate.event.spi.LoadEvent from Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,721 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class org.hibernate.event.spi.LoadEvent locally from Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,723 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class org.hibernate.event.spi.LoadEvent in Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,725 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class org.hibernate.event.spi.LoadEvent in Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,727 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding class org.hibernate.event.spi.LoadEventListener$LoadType from Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,728 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Finding local class org.hibernate.event.spi.LoadEventListener$LoadType from Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,730 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Loading class org.hibernate.event.spi.LoadEventListener$LoadType locally from Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,731 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Attempting to define class org.hibernate.event.spi.LoadEventListener$LoadType in Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,733 TRACE [org.jboss.modules] (http--127.0.0.1-8080-1) Defined class org.hibernate.event.spi.LoadEventListener$LoadType in Module "org.hibernate:main" from local module loader @18d9850 (roots: D:\jboss-as-7.1.1.Final\modules)

                                  16:50:05,735 TRACE [org.hibernate.event.internal.DefaultLoadEventListener] (http--127.0.0.1-8080-1) Loading entity: [com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:05,736 TRACE [org.hibernate.event.internal.DefaultLoadEventListener] (http--127.0.0.1-8080-1) Attempting to resolve: [com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:05,737 TRACE [org.hibernate.event.internal.DefaultLoadEventListener] (http--127.0.0.1-8080-1) Object not resolved in any cache: [com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:05,739 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (http--127.0.0.1-8080-1) Fetching entity: [com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:05,740 DEBUG [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Loading entity: [com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:05,741 TRACE [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of connection method [prepareStatement]

                                  16:50:05,742 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Obtaining JDBC connection

                                  16:50:05,743 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--127.0.0.1-8080-1) Subject: null

                                  16:50:05,744 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                                  16:50:05,744 TRACE [org.jboss.jca.core.connectionmanager.TxConnectionManager] (http--127.0.0.1-8080-1) getManagedConnection interleaving=false , tx=TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING >

                                  16:50:05,746 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionSynchronizationRegistryImple.getTransactionKey

                                  16:50:05,747 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionSynchronizationRegistryImple.getTransactionKey

                                  16:50:05,748 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionSynchronizationRegistryImple.getResource

                                  16:50:05,748 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                                  16:50:05,749 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionSynchronizationRegistryImple.getResource

                                  16:50:05,750 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                                  16:50:05,750 TRACE [org.jboss.jca.core.connectionmanager.pool.strategy.OnePool] (http--127.0.0.1-8080-1) Previous connection tracked by transaction=org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@16bd72d[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@fbde8c connection handles=0 lastUse=1334242192617 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@347cdb pool internal context=SemaphoreArrayListManagedConnectionPool@1d13f05[pool=java:/interfaz_sadesi] xaResource=LocalXAResourceImpl@1cfdfc9[connectionListener=16bd72d connectionManager=1e3c942 warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7, node_name=1, branch_uid=0:ffff0a76a4a2:39bfd35e:4f86ca9a:eb, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync19065386{tx=TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING > wasTrackByTx=true

                                  enlisted=true}] tx=TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING >

                                  16:50:05,758 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) BaseTransaction.getStatus

                                  16:50:05,759 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.getStatus

                                  16:50:05,760 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) Pre-enlist: org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@16bd72d[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@fbde8c connection handles=0 lastUse=1334242192617 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@347cdb pool internal context=SemaphoreArrayListManagedConnectionPool@1d13f05[pool=java:/interfaz_sadesi] xaResource=LocalXAResourceImpl@1cfdfc9[connectionListener=16bd72d connectionManager=1e3c942 warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7, node_name=1, branch_uid=0:ffff0a76a4a2:39bfd35e:4f86ca9a:eb, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync19065386{tx=TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}]

                                  threadTx=TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING >

                                  16:50:05,767 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.equals

                                  16:50:05,768 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.equals

                                  16:50:05,768 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) Get synchronizer org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@16bd72d[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@fbde8c connection handles=0 lastUse=1334242192617 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@347cdb pool internal context=SemaphoreArrayListManagedConnectionPool@1d13f05[pool=java:/interfaz_sadesi] xaResource=LocalXAResourceImpl@1cfdfc9[connectionListener=16bd72d connectionManager=1e3c942 warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7, node_name=1, branch_uid=0:ffff0a76a4a2:39bfd35e:4f86ca9a:eb, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync19065386{tx=TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}]

                                  threadTx=TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING >

                                  16:50:05,776 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.equals

                                  16:50:05,777 TRACE [com.arjuna.ats.jta] (http--127.0.0.1-8080-1) TransactionImple.equals

                                  16:50:05,777 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) Check enlisted org.jboss.jca.core.connectionmanager.listener.TxConnectionListener@16bd72d[state=NORMAL managed connection=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@fbde8c connection handles=0 lastUse=1334242192617 trackByTx=true pool=org.jboss.jca.core.connectionmanager.pool.strategy.OnePool@347cdb pool internal context=SemaphoreArrayListManagedConnectionPool@1d13f05[pool=java:/interfaz_sadesi] xaResource=LocalXAResourceImpl@1cfdfc9[connectionListener=16bd72d connectionManager=1e3c942 warned=false currentXid=< formatId=131077, gtrid_length=29, bqual_length=36, tx_uid=0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7, node_name=1, branch_uid=0:ffff0a76a4a2:39bfd35e:4f86ca9a:eb, subordinatenodename=null, eis_name=unknown eis name >] txSync=TxSync19065386{tx=TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING > wasTrackByTx=true enlisted=true}]

                                  threadTx=TransactionImple < ac, BasicAction: 0:ffff0a76a4a2:39bfd35e:4f86ca9a:e7 status: ActionStatus.RUNNING >

                                  16:50:05,785 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Obtained JDBC connection

                                  16:50:05,791 DEBUG [org.hibernate.SQL] (http--127.0.0.1-8080-1) select peticional0_.id_petic_alta as id1_24_0_, peticional0_.apellido1 as apellido2_24_0_, peticional0_.apellido2 as apellido3_24_0_, peticional0_.clave_usuario as clave4_24_0_, peticional0_.cod_estado as cod5_24_0_, peticional0_.cp as cp24_0_, peticional0_.dni_tutor as dni7_24_0_, peticional0_.domicilio as domicilio24_0_, peticional0_.email_tutor as email9_24_0_, peticional0_.escalera as escalera24_0_, peticional0_.f_entrada as f11_24_0_, peticional0_.f_fecha_nacimiento as f12_24_0_, peticional0_.f_ultima_modificacion as f13_24_0_, peticional0_.foto as foto24_0_, peticional0_.localidad as localidad24_0_, peticional0_.nombre as nombre24_0_, peticional0_.nombre_tutor as nombre17_24_0_, peticional0_.nombre_usuario as nombre18_24_0_, peticional0_.numero as numero24_0_, peticional0_.numero_socio as numero20_24_0_, peticional0_.piso as piso24_0_, peticional0_.provincia as provincia24_0_, peticional0_.puerta as puerta24_0_, peticional0_.sexo as sexo24_

                                  0_, peticional0_.sigla as sigla24_0_, peticional0_.solicita_carnet as solicita26_24_0_, peticional0_.telefono as telefono24_0_ from petic_altas peticional0_ where peticional0_.id_petic_alta=?

                                  16:50:05,800 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Registering statement [org.hibernate.engine.jdbc.internal.proxy.PreparedStatementProxyHandler@1ee63b7[valid=true]]

                                  16:50:05,801 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Registering last query statement [org.hibernate.engine.jdbc.internal.proxy.PreparedStatementProxyHandler@1ee63b7[valid=true]]

                                  16:50:05,803 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractStatementProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of statement method [getWrappedObject]

                                  16:50:05,804 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Registering last query statement [org.jboss.jca.adapters.jdbc.jdk6.WrappedPreparedStatementJDK6@1ee63b7]

                                  16:50:05,805 TRACE [org.hibernate.type.descriptor.sql.BasicBinder] (http--127.0.0.1-8080-1) binding parameter [1] as [VARCHAR] - 249275

                                  16:50:05,806 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractStatementProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of statement method [setString]

                                  16:50:05,807 TRACE [org.hibernate.engine.jdbc.internal.proxy.PreparedStatementProxyHandler] (http--127.0.0.1-8080-1) Binding via setString: [1, 249275]

                                  16:50:05,809 TRACE [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Bound [2] parameters total

                                  16:50:05,809 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractStatementProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of statement method [executeQuery]

                                  16:50:06,004 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Registering result set [org.hibernate.engine.jdbc.internal.proxy.ResultSetProxyHandler@164aaad[valid=true]]

                                  16:50:06,007 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getStatement]

                                  16:50:06,009 TRACE [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Processing result set

                                  16:50:06,011 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [next]

                                  16:50:06,013 DEBUG [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Result set row: 0

                                  16:50:06,014 DEBUG [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Result row: EntityKey[com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:06,016 TRACE [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Initializing object from ResultSet: [com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:06,018 TRACE [org.hibernate.persister.entity.AbstractEntityPersister] (http--127.0.0.1-8080-1) Hydrating entity: [com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:06,021 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,024 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,027 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [atienza] as column [apellido2_24_0_]

                                  16:50:06,029 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,031 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,033 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [atienza] as column [apellido3_24_0_]

                                  16:50:06,034 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,035 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,036 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [marmar] as column [clave4_24_0_]

                                  16:50:06,037 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,038 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,039 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [P] as column [cod5_24_0_]

                                  16:50:06,040 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,041 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,042 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [41530] as column [cp24_0_]

                                  16:50:06,043 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,044 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,046 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [52297477P] as column [dni7_24_0_]

                                  16:50:06,047 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,048 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,049 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [antonio machado] as column [domicilio24_0_]

                                  16:50:06,050 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,051 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,052 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [atienzalucia@gmail.com] as column [email9_24_0_]

                                  16:50:06,053 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,054 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,055 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [] as column [escalera24_0_]

                                  16:50:06,056 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getTimestamp]

                                  16:50:06,057 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,058 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [2012-02-14 10:19:06.0] as column [f11_24_0_]

                                  16:50:06,059 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getDate]

                                  16:50:06,060 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,062 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [2000-04-01] as column [f12_24_0_]

                                  16:50:06,062 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getTimestamp]

                                  16:50:06,064 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,065 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [2012-04-10 19:35:40.0] as column [f13_24_0_]

                                  16:50:06,066 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getBlob]

                                  16:50:06,067 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,068 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [[B@1ba27e9] as column [foto24_0_]

                                  16:50:06,069 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,070 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,072 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [moron de la frontera] as column [localidad24_0_]

                                  16:50:06,073 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,074 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,075 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [marcos] as column [nombre24_0_]

                                  16:50:06,076 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,077 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,078 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [lucia atienza barrera] as column [nombre17_24_0_]

                                  16:50:06,079 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,080 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,082 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [pizzerito] as column [nombre18_24_0_]

                                  16:50:06,082 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,084 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,085 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [19] as column [numero24_0_]

                                  16:50:06,086 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getBigDecimal]

                                  16:50:06,087 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,088 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [0] as column [numero20_24_0_]

                                  16:50:06,089 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,090 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,091 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [] as column [piso24_0_]

                                  16:50:06,092 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,093 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,094 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [sevilla] as column [provincia24_0_]

                                  16:50:06,095 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,096 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,097 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [] as column [puerta24_0_]

                                  16:50:06,098 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,099 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,101 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [V] as column [sexo24_0_]

                                  16:50:06,102 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,103 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,104 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [CL] as column [sigla24_0_]

                                  16:50:06,105 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,106 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,107 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [N] as column [solicita26_24_0_]

                                  16:50:06,108 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getString]

                                  16:50:06,109 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [wasNull]

                                  16:50:06,111 TRACE [org.hibernate.type.descriptor.sql.BasicExtractor] (http--127.0.0.1-8080-1) Found [955853456] as column [telefono24_0_]

                                  16:50:06,112 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [next]

                                  16:50:06,113 TRACE [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Done processing result set (1 rows)

                                  16:50:06,114 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractStatementProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of statement method [close]

                                  16:50:06,115 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Releasing statement [org.hibernate.engine.jdbc.internal.proxy.PreparedStatementProxyHandler@1ee63b7[valid=true]]

                                  16:50:06,116 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Closing result set [org.hibernate.engine.jdbc.internal.proxy.ResultSetProxyHandler@164aaad[valid=true]]

                                  16:50:06,118 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [getWrappedObject]

                                  16:50:06,119 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Closing result set [com.mysql.jdbc.JDBC4ResultSet@164aaad]

                                  16:50:06,120 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [invalidate]

                                  16:50:06,121 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractResultSetProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of ResultSet method [close]

                                  16:50:06,123 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Closing prepared statement [org.hibernate.engine.jdbc.internal.proxy.PreparedStatementProxyHandler@1ee63b7[valid=true]]

                                  16:50:06,124 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractStatementProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of statement method [getWrappedObject]

                                  16:50:06,125 TRACE [org.hibernate.engine.jdbc.internal.JdbcResourceRegistryImpl] (http--127.0.0.1-8080-1) Closing prepared statement [org.jboss.jca.adapters.jdbc.jdk6.WrappedPreparedStatementJDK6@1ee63b7]

                                  16:50:06,127 TRACE [org.hibernate.engine.jdbc.internal.proxy.AbstractStatementProxyHandler] (http--127.0.0.1-8080-1) Handling invocation of statement method [invalidate]

                                  16:50:06,128 TRACE [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Starting after statement execution processing [AFTER_STATEMENT]

                                  16:50:06,129 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Releasing JDBC connection

                                  16:50:06,130 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) connectionClosed called mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@fbde8c

                                  16:50:06,131 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) unregisterConnection: 0 handles left

                                  16:50:06,132 TRACE [org.jboss.jca.core.connectionmanager.listener.TxConnectionListener] (http--127.0.0.1-8080-1) isManagedConnectionFree=false mc=org.jboss.jca.adapters.jdbc.local.LocalManagedConnection@fbde8c

                                  16:50:06,134 DEBUG [org.hibernate.engine.jdbc.internal.LogicalConnectionImpl] (http--127.0.0.1-8080-1) Released JDBC connection

                                  16:50:06,135 TRACE [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Total objects hydrated: 1

                                  16:50:06,135 DEBUG [org.hibernate.engine.internal.TwoPhaseLoad] (http--127.0.0.1-8080-1) Resolving associations for [com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:06,137 DEBUG [org.hibernate.engine.internal.TwoPhaseLoad] (http--127.0.0.1-8080-1) Done materializing entity [com.alteda.labanda.entidades.PeticionAlta#249275]

                                  16:50:06,138 DEBUG [org.hibernate.engine.internal.StatefulPersistenceContext] (http--127.0.0.1-8080-1) Initializing non-lazy collections

                                  16:50:06,139 DEBUG [org.hibernate.loader.Loader] (http--127.0.0.1-8080-1) Done entity load

                                   

                                  I'll stop looking at the log here, since its obvious what happened.  You have three entities that you merged.  EntityClass 'com.alteda.labanda.entidades.Socio' is a new entity, so it shouldn't be inserted into the database (as per the JTA transaction rollback.)  EntityClass 'com.alteda.labanda.entidades.Log' was already inserted into the database in a previous transaction, so rolling back, will not delete it (the merge only loaded it).  I'm not sure of the state of entityClass 'com.alteda.labanda.entidades.PeticionAlta' but that didn't get inserted or loaded.

                                   

                                  So, the two entities that didn't get rolled back, were aleady added to the database in an earlier JTA transaction. 

                                   

                                  Scott

                                  • 14. Re: Problem with transactions...
                                    japochino1

                                    So doesn't work the transactions in jboss as 7?? Help!!!

                                     

                                    Thanks

                                    1 2 Previous Next