Ошибка #15754: При быстром нажатии "Применить фильтр" несколько раз появляется исключение в логе - discussion - Redmine

Действия

Копировать ссылку

Ошибка #15754

открыто

Итерация #15695: ТЗ 22 К21

Улучшение #15720: добавить интерфейс для отображения что предрасчёт запущен

При быстром нажатии "Применить фильтр" несколько раз появляется исключение в логе

Добавил(а) Дмитрий Тереньтьев 7 месяца назад. Обновлено 4 месяца назад.

Статус:

В работе

Приоритет:

Нормальный

Назначена:

Дмитрий Тереньтьев

Дата начала:

20.08.2025

Срок завершения:

Готовность:

Оценка временных затрат:

причина:

возможно, попытка удалить CachedUserEntry, которые уже удалены. Транзакции плохо изолированы.

Описание

[INFO] [talledLocalContainer] 13:50:37.762 [SimpleAsyncTaskExecutor-2] DEBUG r.s.d.s.FilterService - FilterService.refreshCorrespondingUserListAsync thread is SimpleAsyncTaskExecutor-2
[INFO] [talledLocalContainer] 13:50:38.561 [SimpleAsyncTaskExecutor-3] DEBUG r.s.d.s.FilterService - FilterService.refreshCorrespondingUserListAsync thread is SimpleAsyncTaskExecutor-3
[INFO] [talledLocalContainer] 13:50:42.969 [SimpleAsyncTaskExecutor-4] DEBUG r.s.d.s.FilterService - FilterService.refreshCorrespondingUserListAsync thread is SimpleAsyncTaskExecutor-4
[INFO] [talledLocalContainer] 13:50:53.544 [SimpleAsyncTaskExecutor-3] ERROR o.s.a.i.SimpleAsyncUncaughtExceptionHandler - Unexpected exception occurred invoking async method: public void ru.simplex_software.discussion.services.FilterService.refreshCorrespondingUserListAsync(ru.simplex_software.discussion.model.AnonymousUser,org.apache.wicket.model.IModel)
[INFO] [talledLocalContainer] org.springframework.orm.hibernate5.HibernateOptimisticLockingFailureException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1; statement executed: delete from cacheduserentry where id=?; nested exception is org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1; statement executed: delete from cacheduserentry where id=?
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.SessionFactoryUtils.convertHibernateAccessException(SessionFactoryUtils.java:252)
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.HibernateTransactionManager.convertHibernateAccessException(HibernateTransactionManager.java:785)
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:655)
[INFO] [talledLocalContainer] at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:743)
[INFO] [talledLocalContainer] at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:711)
[INFO] [talledLocalContainer] at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:654)
[INFO] [talledLocalContainer] at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:407)
[INFO] [talledLocalContainer] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:119)
[INFO] [talledLocalContainer] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)
[INFO] [talledLocalContainer] at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:753)
[INFO] [talledLocalContainer] at org.springframework.aop.interceptor.AsyncExecutionInterceptor.lambda$invoke$0(AsyncExecutionInterceptor.java:115)
[INFO] [talledLocalContainer] at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[INFO] [talledLocalContainer] at java.base/java.lang.Thread.run(Thread.java:834)
[INFO] [talledLocalContainer] Caused by: org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1; statement executed: delete from cacheduserentry where id=?
[INFO] [talledLocalContainer] at org.hibernate.jdbc.Expectations$BasicExpectation.checkBatched(Expectations.java:67)
[INFO] [talledLocalContainer] at org.hibernate.jdbc.Expectations$BasicExpectation.verifyOutcome(Expectations.java:54)
[INFO] [talledLocalContainer] at org.hibernate.engine.jdbc.batch.internal.NonBatchingBatch.addToBatch(NonBatchingBatch.java:47)
[INFO] [talledLocalContainer] at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3698)
[INFO] [talledLocalContainer] at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3958)
[INFO] [talledLocalContainer] at org.hibernate.action.internal.EntityDeleteAction.execute(EntityDeleteAction.java:123)
[INFO] [talledLocalContainer] at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:604)
[INFO] [talledLocalContainer] at org.hibernate.engine.spi.ActionQueue.lambda$executeActions$1(ActionQueue.java:478)
[INFO] [talledLocalContainer] at java.base/java.util.LinkedHashMap.forEach(LinkedHashMap.java:684)
[INFO] [talledLocalContainer] at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:475)
[INFO] [talledLocalContainer] at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:344)
[INFO] [talledLocalContainer] at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:40)
[INFO] [talledLocalContainer] at org.hibernate.event.service.internal.EventListenerGroupImpl.fireEventOnEachListener(EventListenerGroupImpl.java:107)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.doFlush(SessionImpl.java:1402)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:493)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.flushBeforeTransactionCompletion(SessionImpl.java:3285)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.beforeTransactionCompletion(SessionImpl.java:2420)
[INFO] [talledLocalContainer] at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.beforeTransactionCompletion(JdbcCoordinatorImpl.java:449)
[INFO] [talledLocalContainer] at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl.beforeCompletionCallback(JdbcResourceLocalTransactionCoordinatorImpl.java:183)
[INFO] [talledLocalContainer] at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl.access$300(JdbcResourceLocalTransactionCoordinatorImpl.java:40)
[INFO] [talledLocalContainer] at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl$TransactionDriverControlImpl.commit(JdbcResourceLocalTransactionCoordinatorImpl.java:281)
[INFO] [talledLocalContainer] at org.hibernate.engine.transaction.internal.TransactionImpl.commit(TransactionImpl.java:101)
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:643)
[INFO] [talledLocalContainer] ... 10 common frames omitted
[INFO] [talledLocalContainer] 13:50:53.548 [SimpleAsyncTaskExecutor-4] ERROR o.s.a.i.SimpleAsyncUncaughtExceptionHandler - Unexpected exception occurred invoking async method: public void ru.simplex_software.discussion.services.FilterService.refreshCorrespondingUserListAsync(ru.simplex_software.discussion.model.AnonymousUser,org.apache.wicket.model.IModel)
[INFO] [talledLocalContainer] org.springframework.orm.hibernate5.HibernateOptimisticLockingFailureException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1; statement executed: delete from cacheduserentry where id=?; nested exception is org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1; statement executed: delete from cacheduserentry where id=?
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.SessionFactoryUtils.convertHibernateAccessException(SessionFactoryUtils.java:252)
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.HibernateTransactionManager.convertHibernateAccessException(HibernateTransactionManager.java:785)
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:655)
[INFO] [talledLocalContainer] at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:743)
[INFO] [talledLocalContainer] at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:711)
[INFO] [talledLocalContainer] at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:654)
[INFO] [talledLocalContainer] at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:407)
[INFO] [talledLocalContainer] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:119)
[INFO] [talledLocalContainer] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)
[INFO] [talledLocalContainer] at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:753)
[INFO] [talledLocalContainer] at org.springframework.aop.interceptor.AsyncExecutionInterceptor.lambda$invoke$0(AsyncExecutionInterceptor.java:115)
[INFO] [talledLocalContainer] at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[INFO] [talledLocalContainer] at java.base/java.lang.Thread.run(Thread.java:834)
[INFO] [talledLocalContainer] Caused by: org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1; statement executed: delete from cacheduserentry where id=?
[INFO] [talledLocalContainer] at org.hibernate.jdbc.Expectations$BasicExpectation.checkBatched(Expectations.java:67)
[INFO] [talledLocalContainer] at org.hibernate.jdbc.Expectations$BasicExpectation.verifyOutcome(Expectations.java:54)
[INFO] [talledLocalContainer] at org.hibernate.engine.jdbc.batch.internal.NonBatchingBatch.addToBatch(NonBatchingBatch.java:47)
[INFO] [talledLocalContainer] at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3698)
[INFO] [talledLocalContainer] at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3958)
[INFO] [talledLocalContainer] at org.hibernate.action.internal.EntityDeleteAction.execute(EntityDeleteAction.java:123)
[INFO] [talledLocalContainer] at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:604)
[INFO] [talledLocalContainer] at org.hibernate.engine.spi.ActionQueue.lambda$executeActions$1(ActionQueue.java:478)
[INFO] [talledLocalContainer] at java.base/java.util.LinkedHashMap.forEach(LinkedHashMap.java:684)
[INFO] [talledLocalContainer] at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:475)
[INFO] [talledLocalContainer] at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:344)
[INFO] [talledLocalContainer] at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:40)
[INFO] [talledLocalContainer] at org.hibernate.event.service.internal.EventListenerGroupImpl.fireEventOnEachListener(EventListenerGroupImpl.java:107)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.doFlush(SessionImpl.java:1402)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:493)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.flushBeforeTransactionCompletion(SessionImpl.java:3285)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.beforeTransactionCompletion(SessionImpl.java:2420)
[INFO] [talledLocalContainer] at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.beforeTransactionCompletion(JdbcCoordinatorImpl.java:449)
[INFO] [talledLocalContainer] at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl.beforeCompletionCallback(JdbcResourceLocalTransactionCoordinatorImpl.java:183)
[INFO] [talledLocalContainer] at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl.access$300(JdbcResourceLocalTransactionCoordinatorImpl.java:40)
[INFO] [talledLocalContainer] at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl$TransactionDriverControlImpl.commit(JdbcResourceLocalTransactionCoordinatorImpl.java:281)
[INFO] [talledLocalContainer] at org.hibernate.engine.transaction.internal.TransactionImpl.commit(TransactionImpl.java:101)
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:643)
[INFO] [talledLocalContainer] ... 10 common frames omitted
[INFO] [talledLocalContainer] 13:50:53.652 [SimpleAsyncTaskExecutor-5] DEBUG r.s.d.s.FilterService - FilterService.refreshCorrespondingUserListAsync thread is SimpleAsyncTaskExecutor-5
[INFO] [talledLocalContainer] 13:50:54.609 [SimpleAsyncTaskExecutor-6] DEBUG r.s.d.s.FilterService - FilterService.refreshCorrespondingUserListAsync thread is SimpleAsyncTaskExecutor-6
[INFO] [talledLocalContainer] 13:50:55.636 [SimpleAsyncTaskExecutor-7] DEBUG r.s.d.s.FilterService - FilterService.refreshCorrespondingUserListAsync thread is SimpleAsyncTaskExecutor-7
[INFO] [talledLocalContainer] 13:51:05.290 [SimpleAsyncTaskExecutor-6] ERROR o.s.a.i.SimpleAsyncUncaughtExceptionHandler - Unexpected exception occurred invoking async method: public void ru.simplex_software.discussion.services.FilterService.refreshCorrespondingUserListAsync(ru.simplex_software.discussion.model.AnonymousUser,org.apache.wicket.model.IModel)
[INFO] [talledLocalContainer] org.springframework.orm.hibernate5.HibernateOptimisticLockingFailureException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1; statement executed: delete from cacheduserentry where id=?; nested exception is org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1; statement executed: delete from cacheduserentry where id=?
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.SessionFactoryUtils.convertHibernateAccessException(SessionFactoryUtils.java:252)
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.HibernateTransactionManager.convertHibernateAccessException(HibernateTransactionManager.java:785)
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:655)
[INFO] [talledLocalContainer] at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:743)
[INFO] [talledLocalContainer] at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:711)
[INFO] [talledLocalContainer] at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:654)
[INFO] [talledLocalContainer] at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:407)
[INFO] [talledLocalContainer] at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:119)
[INFO] [talledLocalContainer] at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)
[INFO] [talledLocalContainer] at org.springframework.aop.framework.CglibAopProxy$CglibMethodInvocation.proceed(CglibAopProxy.java:753)
[INFO] [talledLocalContainer] at org.springframework.aop.interceptor.AsyncExecutionInterceptor.lambda$invoke$0(AsyncExecutionInterceptor.java:115)
[INFO] [talledLocalContainer] at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[INFO] [talledLocalContainer] at java.base/java.lang.Thread.run(Thread.java:834)
[INFO] [talledLocalContainer] Caused by: org.hibernate.StaleStateException: Batch update returned unexpected row count from update [0]; actual row count: 0; expected: 1; statement executed: delete from cacheduserentry where id=?
[INFO] [talledLocalContainer] at org.hibernate.jdbc.Expectations$BasicExpectation.checkBatched(Expectations.java:67)
[INFO] [talledLocalContainer] at org.hibernate.jdbc.Expectations$BasicExpectation.verifyOutcome(Expectations.java:54)
[INFO] [talledLocalContainer] at org.hibernate.engine.jdbc.batch.internal.NonBatchingBatch.addToBatch(NonBatchingBatch.java:47)
[INFO] [talledLocalContainer] at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3698)
[INFO] [talledLocalContainer] at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:3958)
[INFO] [talledLocalContainer] at org.hibernate.action.internal.EntityDeleteAction.execute(EntityDeleteAction.java:123)
[INFO] [talledLocalContainer] at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:604)
[INFO] [talledLocalContainer] at org.hibernate.engine.spi.ActionQueue.lambda$executeActions$1(ActionQueue.java:478)
[INFO] [talledLocalContainer] at java.base/java.util.LinkedHashMap.forEach(LinkedHashMap.java:684)
[INFO] [talledLocalContainer] at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:475)
[INFO] [talledLocalContainer] at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:344)
[INFO] [talledLocalContainer] at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:40)
[INFO] [talledLocalContainer] at org.hibernate.event.service.internal.EventListenerGroupImpl.fireEventOnEachListener(EventListenerGroupImpl.java:107)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.doFlush(SessionImpl.java:1402)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:493)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.flushBeforeTransactionCompletion(SessionImpl.java:3285)
[INFO] [talledLocalContainer] at org.hibernate.internal.SessionImpl.beforeTransactionCompletion(SessionImpl.java:2420)
[INFO] [talledLocalContainer] at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.beforeTransactionCompletion(JdbcCoordinatorImpl.java:449)
[INFO] [talledLocalContainer] at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl.beforeCompletionCallback(JdbcResourceLocalTransactionCoordinatorImpl.java:183)
[INFO] [talledLocalContainer] at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl.access$300(JdbcResourceLocalTransactionCoordinatorImpl.java:40)
[INFO] [talledLocalContainer] at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl$TransactionDriverControlImpl.commit(JdbcResourceLocalTransactionCoordinatorImpl.java:281)
[INFO] [talledLocalContainer] at org.hibernate.engine.transaction.internal.TransactionImpl.commit(TransactionImpl.java:101)
[INFO] [talledLocalContainer] at org.springframework.orm.hibernate5.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:643)
[INFO] [talledLocalContainer] ... 10 common frames omitted