]
Tom Jenkinson closed JBTM-2773.
-------------------------------
Resolution: Duplicate Issue
is incorporated by JBTM-2769
NPE when trying delete heuristic transaction (with CMR resource) from
JDBC tx log-store
---------------------------------------------------------------------------------------
Key: JBTM-2773
URL:
https://issues.jboss.org/browse/JBTM-2773
Project: JBoss Transaction Manager
Issue Type: Bug
Components: Transaction Core
Affects Versions: 5.3.5.Final
Reporter: Daniel Simko
Assignee: Tom Jenkinson
Priority: Critical
Part of the test is cleaning tx log-store:
{code}
14:36:46,747 ERROR [org.jboss.as.test.jbossts.base.setup.operations.TMOperations] (main)
Management operation {
"operation" => "delete",
"address" => [
("subsystem" => "transactions"),
("log-store" => "log-store"),
("transactions" =>
"0:ffff7f000001:-475b5c9c:5804af44:31")
]
} failed: {
"outcome" => "failed",
"failure-description" => "java.lang.NullPointerException",
"rolled-back" => true
}
{code}
but fails with NPE. Server log:
{code}
2016-10-17 14:36:42,798 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
InputObjectState::InputObjectState()
2016-10-17 14:36:42,798 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
OutputObjectState::OutputObjectState()
2016-10-17 14:36:43,140 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
StateManager::StateManager( 0:ffff7f000001:-475b5c9c:5804af44:31 )
2016-10-17 14:36:43,140 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
BasicAction::BasicAction(0:ffff7f000001:-475b5c9c:5804af44:31)
2016-10-17 14:36:43,141 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
BasicAction::activate() for action-id 0:ffff7f000001:-475b5c9c:5804af44:31
2016-10-17 14:36:43,308 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
InputObjectState::InputObjectState(0:ffff7f000001:-475b5c9c:5804af44:31,
StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction)
2016-10-17 14:36:43,474 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
BasicAction::restore_state ()
2016-10-17 14:36:43,475 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
StateManager.unpackHeader for object-id 0:ffff7f000001:-475b5c9c:5804af44:31 birth-date
1476707800081
2016-10-17 14:36:43,475 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
Unpacked a 463 record
2016-10-17 14:36:43,475 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
HeuristicList - Unpacked heuristic list size of 1
2016-10-17 14:36:43,475 WARN [com.arjuna.ats.arjuna] (management-handler-thread - 4)
Transaction 0:ffff7f000001:-475b5c9c:5804af44:31 has 1 heuristic participant(s)!
2016-10-17 14:36:43,475 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
HeuristicList - Unpacked a 50 record
2016-10-17 14:36:43,475 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
StateManager::StateManager( 0:0:0:0:0 )
2016-10-17 14:36:43,475 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
AbstractRecord::AbstractRecord () - crash recovery constructor
2016-10-17 14:36:43,475 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
CommitMarkableResourceRecord.CommitMarkableResourceRecord (), record
id=-8000000000000000:-8000000000000000:-80000000:-80000000:-80000000
2016-10-17 14:36:43,475 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
unpack: java:jboss/xa-datasources/CrashRecoveryDS
2016-10-17 14:36:43,475 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
unpack: < formatId=131077, gtrid_length=29, bqual_length=36,
tx_uid=0:ffff7f000001:-475b5c9c:5804af44:31, node_name=1,
branch_uid=0:ffff7f000001:-475b5c9c:5804af44:37, subordinatenodename=null,
eis_name=java:jboss/xa-datasources/CrashRecoveryDS >
2016-10-17 14:36:43,476 TRACE [com.arjuna.ats.jta] (management-handler-thread - 4)
TransactionSynchronizationRegistryImple.getTransactionKey
2016-10-17 14:36:43,476 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
Attempting to delete number of entries: 2
2016-10-17 14:36:43,991 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
CommitMarkableResourceRecordRecoveryModule::periodicWorkFirstPass
2016-10-17 14:36:43,991 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
CommitMarkableResourceRecordRecoveryModule::connecting to:
java:jboss/xa-datasources/CrashRecoveryDS
2016-10-17 14:36:43,991 TRACE [com.arjuna.ats.jta] (management-handler-thread - 4)
TransactionSynchronizationRegistryImple.getTransactionKey
2016-10-17 14:36:44,334 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
InputObjectState::InputObjectState()
2016-10-17 14:36:44,335 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
OutputObjectState::OutputObjectState()
2016-10-17 14:36:44,669 DEBUG [com.arjuna.ats.arjuna] (management-handler-thread - 4)
processing /StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction transactions
2016-10-17 14:36:44,669 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
InputObjectState::InputObjectState()
2016-10-17 14:36:44,669 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
OutputObjectState::OutputObjectState()
2016-10-17 14:36:45,002 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
InputObjectState::InputObjectState()
2016-10-17 14:36:45,002 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
OutputObjectState::OutputObjectState()
2016-10-17 14:36:45,838 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
InputObjectState::InputObjectState(0:ffff7f000001:-475b5c9c:5804af44:31,
StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction)
2016-10-17 14:36:46,004 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
StateManager::StateManager( 0:ffff7f000001:-475b5c9c:5804af44:31 )
2016-10-17 14:36:46,004 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
BasicAction::BasicAction(0:ffff7f000001:-475b5c9c:5804af44:31)
2016-10-17 14:36:46,005 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
StateManager.unpackHeader for object-id 0:ffff7f000001:-475b5c9c:5804af44:31 birth-date
1476707800081
2016-10-17 14:36:46,005 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
wasCommitted< formatId=131077, gtrid_length=29, bqual_length=36,
tx_uid=0:ffff7f000001:-475b5c9c:5804af44:31, node_name=1,
branch_uid=0:ffff7f000001:-475b5c9c:5804af44:37, subordinatenodename=null,
eis_name=java:jboss/xa-datasources/CrashRecoveryDS > null
2016-10-17 14:36:46,005 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
RecordList::insert(RecordList: empty) : appending
/StateManager/AbstractRecord/CommitMarkableResourceRecord for
-8000000000000000:-8000000000000000:-80000000:-80000000:-80000000
2016-10-17 14:36:46,005 WARN [com.arjuna.ats.arjuna] (management-handler-thread - 4)
Transaction 0:ffff7f000001:-475b5c9c:5804af44:31 restored heuristic participant
com.arjuna.ats.internal.jta.resources.arjunacore.CommitMarkableResourceRecord@2493dae0
2016-10-17 14:36:46,005 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
HeuristicList - Unpacked a 463 record
2016-10-17 14:36:46,005 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
Restored action status of ActionStatus.COMMITTED 7
2016-10-17 14:36:46,005 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
Restored action type Top-level 0
2016-10-17 14:36:46,005 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
Restored heuristic decision of TwoPhaseOutcome.HEURISTIC_ROLLBACK 3
2016-10-17 14:36:46,005 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
StateManager::StateManager( 0:ffff7f000001:-475b5c9c:5804af44:31 )
2016-10-17 14:36:46,174 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
InputObjectState::InputObjectState(0:ffff7f000001:-475b5c9c:5804af44:31,
StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction)
2016-10-17 14:36:46,344 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
StateManager::activate( null) for object-id 0:0:0:0:0
2016-10-17 14:36:46,345 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
StateManager::setupStore ( null )
2016-10-17 14:36:46,682 WARN [com.arjuna.ats.arjuna] (management-handler-thread - 4)
ARJUNA012035: Activate of object with id = 0:0:0:0:0 and type
/StateManager/AbstractRecord/CommitMarkableResourceRecord unexpectedly failed
2016-10-17 14:36:46,682 TRACE [com.arjuna.ats.arjuna] (management-handler-thread - 4)
Registering:
jboss.jta:type=ObjectStore,itype=StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction,uid=0_ffff7f000001_-475b5c9c_5804af44_31
2016-10-17 14:36:46,682 DEBUG [com.arjuna.ats.arjuna] (management-handler-thread - 4)
registering bean
jboss.jta:type=ObjectStore,itype=StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction,uid=0_ffff7f000001_-475b5c9c_5804af44_31
2016-10-17 14:36:46,683 DEBUG [com.arjuna.ats.arjuna] (management-handler-thread - 4)
registering bean
jboss.jta:type=ObjectStore,itype=StateManager/BasicAction/TwoPhaseCoordinator/AtomicAction,uid=0_ffff7f000001_-475b5c9c_5804af44_31,puid=-8000000000000000_-8000000000000000_-80000000_-80000000_-80000000
{code}
Test passed for EAP 7.1.0.DR5 but failed for EAP 7.1.0.DR6. For another types of
log-store (standard file based, activemq artemis journal) test passed.