Details
-
Bug
-
Resolution: Done
-
Major
-
None
-
None
Description
QA objectstore failed with db2 database as objectstore. The error is probably due to a synchronization problem in class 'SimplePooledDynamicDataSourceJDBCAccess'. The error is not always thrown.
2023-12-05 10:48:58,347 err: exception in worker001: java.lang.ArrayIndexOutOfBoundsException: arraycopy: last source index 11 out of bounds for object array[10] 2023-12-05 10:48:58,347 err: java.lang.ArrayIndexOutOfBoundsException: arraycopy: last source index 11 out of bounds for object array[10] 2023-12-05 10:48:58,347 err: at java.base/java.lang.System.arraycopy(Native Method) 2023-12-05 10:48:58,347 err: at java.base/java.util.ArrayList.fastRemove(ArrayList.java:673) 2023-12-05 10:48:58,347 err: at java.base/java.util.ArrayList.remove(ArrayList.java:661) 2023-12-05 10:48:58,347 err: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.accessors.SimplePooledDynamicDataSourceJDBCAccess$WrappedConnection.close(SimplePooledDynamicDataSourceJDBCAccess.java:116) 2023-12-05 10:48:58,347 err: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCImple_driver.remove_state(JDBCImple_driver.java:491) 2023-12-05 10:48:58,347 err: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCStore.remove_committed(JDBCStore.java:126) 2023-12-05 10:48:58,347 err: at com.arjuna.ats.arjuna.coordinator.BasicAction.updateState(BasicAction.java:3383) 2023-12-05 10:48:58,347 err: at com.arjuna.ats.arjuna.coordinator.BasicAction.phase2Commit(BasicAction.java:1968) 2023-12-05 10:48:58,347 err: at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1549) 2023-12-05 10:48:58,347 err: at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:71) 2023-12-05 10:48:58,347 err: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:135) 2023-12-05 10:48:58,347 err: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:120) 2023-12-05 10:48:58,347 err: at org.jboss.jbossts.qa.ArjunaCore.LockManager.client.Worker003.run(Worker003.java:69)
2023-12-05 10:48:58,720 err: Dec 05, 2023 10:48:58 AM com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCImple_driver write_state 2023-12-05 10:48:58,720 err: WARN: ARJUNA012258: JDBCImple:write_state caught exception 2023-12-05 10:48:58,720 err: java.lang.NullPointerException 2023-12-05 10:48:58,720 err: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCImple_driver.write_state(JDBCImple_driver.java:601) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.internal.arjuna.objectstore.jdbc.JDBCStore.write_uncommitted(JDBCStore.java:138) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.arjuna.StateManager.deactivate(StateManager.java:436) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.internal.arjuna.abstractrecords.PersistenceRecord.topLevelPrepare(PersistenceRecord.java:259) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.arjuna.coordinator.BasicAction.doPrepare(BasicAction.java:2750) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.arjuna.coordinator.BasicAction.doPrepare(BasicAction.java:2700) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.arjuna.coordinator.BasicAction.prepare(BasicAction.java:2213) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.arjuna.coordinator.BasicAction.End(BasicAction.java:1523) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.arjuna.coordinator.TwoPhaseCoordinator.end(TwoPhaseCoordinator.java:71) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:135) 2023-12-05 10:48:58,720 err: at com.arjuna.ats.arjuna.AtomicAction.commit(AtomicAction.java:120) 2023-12-05 10:48:58,720 err: at org.jboss.jbossts.qa.ArjunaCore.LockManager.client.Worker003.run(Worker003.java:69) 2023-12-05 10:48:58,720 err: 2023-12-05 10:48:58,720 err: Dec 05, 2023 10:48:58 AM com.arjuna.ats.internal.arjuna.abstractrecords.PersistenceRecord topLevelPrepare 2023-12-05 10:48:58,720 err: WARN: ARJUNA012023: PersistenceRecord deactivate error, object probably already deactivated! 2023-12-05 10:48:58,720 err: Dec 05, 2023 10:48:58 AM com.arjuna.ats.arjuna.coordinator.BasicAction End 2023-12-05 10:48:58,720 err: WARN: ARJUNA012073: BasicAction.End() - prepare phase of action-id 0:ffffac1d0997:8779:656f0018:144e failed. 2023-12-05 10:48:58,721 err: Dec 05, 2023 10:48:58 AM com.arjuna.ats.arjuna.coordinator.BasicAction End 2023-12-05 10:48:58,721 err: WARN: ARJUNA012075: Action Aborting
For more details look at the attached zip and specifically the client_0_output.txt where the error is shown.
