Почему 'javax.resource.ResourceException: IJ000459: Transaction is not active' появляется только при первой попытке - PullRequest
0 голосов
/ 19 июня 2020

Spring data jpa используется в среде cdi / jee, запущенной на сервере приложений Wildfly.

В приложении 3 уровня:

  1. jax rs / rest service, запускается через бизнес-службу http

  2. , с примененной аннотацией JEE @Stateless, без явных аннотаций, связанных с транзакциями. По умолчанию в этом случае применяется аннотация @TransactionAttribute(REQUIRED)

  3. jpa-репозиторий spring data, используемый для сохранения jpa-сущности, все стандартно

Когда веб-служба запускается через url в первый раз после развертывания , я получаю исключение:

Вызвано: javax.resource.ResourceException: IJ000460: Проверка ошибок для транзакции в org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.getManagedConnection (TxConnectionManagerImpl. java: 424) на org.jboss.jca.core.connectionmanager.AbstractConnectionManager.allocateConnectionManager 10 или 7: AbstractConnectionManager.allocateConnection47 *. .jboss.jca.adapters.jdb c .WrapperDataSource.getConnection (WrapperDataSource. java: 138) ... еще 185 Причина: javax.resource.ResourceException: IJ000459: Транзакция неактивна: tx = TransactionImple

at org.jboss.jca.core.connectionmanager.tx.TxConnectionManagerImpl.getManagedConnection(TxConnectionManagerImpl.java:408)

... еще 187

Следующее срабатывание logi c работает нормально, сущность jpa сохраняется и никаких исключений. Ни NPE, ни таймаут (для обработки logi c требуется несколько секунд).

Что-то помечает транзакцию ActionStatus.ABORT_ONLY. Я установил com.arjuna ведение журнала на INFO и получил:

2020-06-19 09:31:40,666 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,667 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,668 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,670 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,671 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getRollbackOnly
2020-06-19 09:31:40,671 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,672 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,672 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.registerInterposedSynchronization - Class: class org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList HashCode: 362705761 toString: org.jboss.as.txn.service.internal.tsr.JCAOrderedLastSynchronizationList@159e7361
2020-06-19 09:31:40,673 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,673 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,674 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,674 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.equals
2020-06-19 09:31:40,675 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.putResource
2020-06-19 09:31:40,675 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,676 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,677 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,677 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,678 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,678 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,679 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,679 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,680 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,680 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,743 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,744 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,744 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,754 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,754 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,755 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,866 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,866 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,867 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,867 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,868 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getRollbackOnly
2020-06-19 09:31:40,868 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,869 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,871 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,871 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,874 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,874 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,875 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,877 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,877 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,877 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,879 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,879 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,880 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,880 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,881 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getRollbackOnly
2020-06-19 09:31:40,882 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,882 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,883 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,884 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_ACTIVE
2020-06-19 09:31:40,885 TRACE [com.arjuna.ats.jta] (default task-81) BaseTransaction.setRollbackOnly
2020-06-19 09:31:40,886 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.setRollbackOnly
2020-06-19 09:31:40,886 TRACE [com.arjuna.ats.arjuna] (default task-81) BasicAction::preventCommit( BasicAction: 0:ffff7f000101:-29ced44d:5eec6925:42f6 status: ActionStatus.RUNNING)
2020-06-19 09:31:40,887 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,888 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,889 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,890 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,891 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,891 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,892 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,892 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,893 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,893 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,894 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,894 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,895 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,895 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,896 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,900 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,901 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,901 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,902 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,902 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,903 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,903 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,904 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,904 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,905 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,905 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,906 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,906 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,907 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,907 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,908 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,908 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,909 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,909 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,910 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,910 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,911 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,911 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,912 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,912 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,913 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,913 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,914 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,914 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,915 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,915 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,916 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,916 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,917 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,917 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,918 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,918 TRACE [com.arjuna.ats.jta] (default task-81) BaseTransaction.setRollbackOnly
2020-06-19 09:31:40,919 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.setRollbackOnly
2020-06-19 09:31:40,919 TRACE [com.arjuna.ats.arjuna] (default task-81) BasicAction::preventCommit( BasicAction: 0:ffff7f000101:-29ced44d:5eec6925:42f6 status: ActionStatus.ABORT_ONLY)
2020-06-19 09:31:40,920 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,920 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,921 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,922 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,923 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,923 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,924 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,924 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,937 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,939 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,940 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,941 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,941 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,947 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,948 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,949 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,961 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,963 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,963 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,964 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,965 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,965 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,966 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,966 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,967 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getTransactionKey
2020-06-19 09:31:40,967 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,968 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,968 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,969 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getTransactionKey
2020-06-19 09:31:40,970 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,970 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,971 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,971 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.putResource
2020-06-19 09:31:40,972 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,972 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,973 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:40,973 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,974 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,976 WARN  [org.hibernate.engine.jdbc.spi.SqlExceptionHelper] (default task-81) SQL Error: 0, SQLState: null
2020-06-19 09:31:40,976 ERROR [org.hibernate.engine.jdbc.spi.SqlExceptionHelper] (default task-81) javax.resource.ResourceException: IJ000460: Error checking for a transaction
2020-06-19 09:31:40,978 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,979 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,979 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,980 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,981 TRACE [com.arjuna.ats.jta] (default task-81) BaseTransaction.setRollbackOnly
2020-06-19 09:31:40,981 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.setRollbackOnly
2020-06-19 09:31:40,982 TRACE [com.arjuna.ats.arjuna] (default task-81) BasicAction::preventCommit( BasicAction: 0:ffff7f000101:-29ced44d:5eec6925:42f6 status: ActionStatus.ABORT_ONLY)
2020-06-19 09:31:40,996 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,997 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,997 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,998 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,998 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,999 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:40,999 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,000 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,001 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,001 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,002 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,004 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,005 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,006 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getTransactionKey
2020-06-19 09:31:41,007 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:41,007 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,008 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,009 TRACE [com.arjuna.ats.jta] (default task-81) TransactionSynchronizationRegistryImple.getResource
2020-06-19 09:31:41,009 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,010 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,011 WARN  [org.hibernate.engine.jdbc.spi.SqlExceptionHelper] (default task-81) SQL Error: 0, SQLState: null
2020-06-19 09:31:41,011 ERROR [org.hibernate.engine.jdbc.spi.SqlExceptionHelper] (default task-81) javax.resource.ResourceException: IJ000460: Error checking for a transaction
2020-06-19 09:31:41,012 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,012 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,013 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,013 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.getStatus: javax.transaction.Status.STATUS_MARKED_ROLLBACK
2020-06-19 09:31:41,014 TRACE [com.arjuna.ats.jta] (default task-81) BaseTransaction.setRollbackOnly
2020-06-19 09:31:41,014 TRACE [com.arjuna.ats.jta] (default task-81) TransactionImple.setRollbackOnly
2020-06-19 09:31:41,014 TRACE [com.arjuna.ats.arjuna] (default task-81) BasicAction::preventCommit( BasicAction: 0:ffff7f000101:-29ced44d:5eec6925:42f6 status: ActionStatus.ABORT_ONLY)

Не вижу здесь, что именно вызывает проблему и почему это происходит только в первый раз.

Вопросы являются:

  1. есть ли способ увидеть настоящую причину? Можно ли запросить эту функцию? Смешно, что так трудно найти первопричину.

  2. в чем может быть причина такого поведения, когда это происходит только в первый раз, а потом продолжает работать хорошо.

  3. могу ли я изменить поведение по умолчанию и вместо того, чтобы полагаться на маркировку транзакций для отката, полагаться на try / catch logi c.

В качестве обходного пути я добавил класс, помеченный @Singleton и @Startup. Определен метод инициализации, помеченный @PostConstruct. И теперь при запуске срабатываю "неправильным" методом. => Итак, при запуске я получаю эту ошибку. Дальше все работает нормально. Но нужно найти первопричину и лучший подход.

...