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'.
Hibernate releases the db connection (which should be cached by the cached connection manager for the current JTA transaction):
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.