org.infinispan.util.concurrent.TimeoutException：ISPN000299：15 秒后无法获取密钥

Question

Hibernate L2 缓存存在以下问题：

• 线程 1

  • 执行本机查询：DELETE ... FROM WHERE ... => 整个 L2 缓存失效
  • 长时间运行的进程

• 线程 2

  • 执行本机查询：UPDATE SET ... WHERE ... => 整个 L2 缓存失效
  • 线程被线程 1 阻塞
  • 15 秒后，ISPN000299 升高。

这是完整的堆栈跟踪：

2020-03-11 15:06:01,384 ERROR [InvocationContextInterceptor] ISPN000136: Error executing command RemoveCommand, writing keys [com.sylob.cochise.dm1.ejb.entite.plageHoraire.PlageHoraireCoreEntite#4028ae256b033913016b0342b7ed6229]: org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after 15 seconds for key com.sylob.cochise.dm1.ejb.entite.plageHoraire.PlageHoraireCoreEntite#4028ae256b033913016b0342b7ed6229 and requestor GlobalTransaction:<null>:1485:local. Lock is held by GlobalTransaction:<null>:1402:local
    at org.infinispan.util.concurrent.locks.impl.DefaultLockManager$KeyAwareExtendedLockPromise.lock(DefaultLockManager.java:238)
    at org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockAndRecord(AbstractLockingInterceptor.java:193)
    at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.checkPendingAndLockKey(AbstractTxLockingInterceptor.java:193)
    at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.lockOrRegisterBackupLock(AbstractTxLockingInterceptor.java:116)
    at org.infinispan.interceptors.locking.PessimisticLockingInterceptor.visitDataWriteCommand(PessimisticLockingInterceptor.java:134)
    at org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitRemoveCommand(AbstractLockingInterceptor.java:75)
    at org.infinispan.commands.write.RemoveCommand.acceptVisitor(RemoveCommand.java:67)
    at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:99)
    at org.infinispan.interceptors.TxInterceptor.enlistWriteAndInvokeNext(TxInterceptor.java:367)
    at org.infinispan.interceptors.TxInterceptor.visitRemoveCommand(TxInterceptor.java:231)
    at org.infinispan.commands.write.RemoveCommand.acceptVisitor(RemoveCommand.java:67)
    at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:99)
    at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:114)
    at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:83)
    at org.infinispan.commands.AbstractVisitor.visitRemoveCommand(AbstractVisitor.java:48)
    at org.infinispan.commands.write.RemoveCommand.acceptVisitor(RemoveCommand.java:67)
    at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:335)
    at org.infinispan.cache.impl.CacheImpl.executeCommandAndCommitIfNeeded(CacheImpl.java:1672)
    at org.infinispan.cache.impl.CacheImpl.removeInternal(CacheImpl.java:557)
    at org.infinispan.cache.impl.CacheImpl.remove(CacheImpl.java:549)
    at org.infinispan.cache.impl.CacheImpl.remove(CacheImpl.java:543)
    at org.infinispan.cache.impl.AbstractDelegatingCache.remove(AbstractDelegatingCache.java:296)
    at org.hibernate.cache.infinispan.util.Caches.removeAll(Caches.java:285)
    at org.hibernate.cache.infinispan.access.InvalidationCacheAccessDelegate.removeAll(InvalidationCacheAccessDelegate.java:149)
    at org.hibernate.cache.infinispan.entity.ReadOnlyAccess.removeAll(ReadOnlyAccess.java:65)
    at org.hibernate.action.internal.BulkOperationCleanupAction$EntityCleanup.<init>(BulkOperationCleanupAction.java:210)
    at org.hibernate.action.internal.BulkOperationCleanupAction$EntityCleanup.<init>(BulkOperationCleanupAction.java:203)
    at org.hibernate.action.internal.BulkOperationCleanupAction.<init>(BulkOperationCleanupAction.java:110)
    at org.hibernate.engine.query.spi.NativeSQLQueryPlan.coordinateSharedCacheCleanup(NativeSQLQueryPlan.java:152)
    at org.hibernate.engine.query.spi.NativeSQLQueryPlan.performExecuteUpdate(NativeSQLQueryPlan.java:176)
    at org.hibernate.internal.SessionImpl.executeNativeUpdate(SessionImpl.java:1373)
    at org.hibernate.internal.SQLQueryImpl.executeUpdate(SQLQueryImpl.java:373)

我正在运行 Wildfly 10.1.0.Final（独立的完整配置文件）和 Hibernate 5.0.10.Final / Infinispan 8.2.4.Final。
环境未集群，会话未复制。

我正在使用 Wildfly 为 Infinispan L2 缓存提供的默认配置：

<cache-container name="hibernate" default-cache="local-query" module="org.hibernate.infinispan">
    <local-cache name="entity">
        <transaction mode="NON_XA"/>
        <eviction strategy="LRU" max-entries="10000"/>
        <expiration max-idle="100000"/>
    </local-cache>
    <local-cache name="local-query">
        <eviction strategy="LRU" max-entries="10000"/>
        <expiration max-idle="100000"/>
    </local-cache>
    <local-cache name="timestamps"/>
</cache-container>

使用 Hibernate 3.x / EhCache，这曾经可以工作。

这里有什么问题？

感谢任何支持...

Answer 1

本机 SQL 批量更新会使整个 L2 缓存失效。这不是你想要的。所以你必须通过使用来告诉hibernate，例如：

query.unwrap(SQLQuery.class).addSynchronizedEntityClass(YourEntity.class).executeUpdate();

这应该避免使整个缓存失效。

另外，看看类 org.hibernate.action.internal.BulkOperationCleanupAction

参考：https://www.baeldung.com/hibernate-second-level-cache 见第 10 项。

Answer 2

理论上，没有错。然而，由于一些限制（试图正确），这两者都试图在单个事务中一个一个地删除条目，这确实需要很长时间。

WildFly 10 已经超过 4 年了，Hibernate 5.0.10 也是如此。我不记得在哪个次要/微型版本中修复了哪些问题，但是使用足够新的 Hibernate 版本，您应该使用<transaction mode="NONE"/>，虽然数据库事务语义将得到维护，但这个问题应该会消失。