[JBoss JIRA] (ISPN-4887) Stale locks in non-tx cache after merge

Tuesday, 6 October 2015

    [
https://issues.jboss.org/browse/ISPN-4887?page=com.atlassian.jira.plugin....
] 

Vladimir Dzhuvinov edited comment on ISPN-4887 at 10/6/15 2:11 PM:
-------------------------------------------------------------------

We have observed the same exception in logs of a server using Infinispan 7.2.3 in non-tx
replicated cluster:

{{ISPN000136: Execution error
org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after
10 seconds for key I9AIaoAPU1N_HqvOeamQ-YZg9qHvo-K2vP6Ksq6t6Yk and requestor
Thread[remote-thread--p3-t23052,5,main]. Lock is held by
Thread[remote-thread--p3-t23108,5,main], while request came from stage-auth-app02-2214
        at
org.infinispan.util.concurrent.locks.LockManagerImpl.lock(LockManagerImpl.java:198)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.util.concurrent.locks.LockManagerImpl.acquireLockNoCheck(LockManagerImpl.java:181)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:166)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:162)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitNonTxDataWriteCommand(AbstractLockingInterceptor.java:87)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.NonTransactionalLockingInterceptor.visitDataWriteCommand(NonTransactionalLockingInterceptor.java:40)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitReplaceCommand(AbstractLockingInterceptor.java:60)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:111)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.AbstractVisitor.visitReplaceCommand(AbstractVisitor.java:54)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.handleNonTxWriteCommand(StateTransferInterceptor.java:311)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.handleWriteCommand(StateTransferInterceptor.java:249)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.visitReplaceCommand(StateTransferInterceptor.java:128)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.CacheMgmtInterceptor.updateStoreStatistics(CacheMgmtInterceptor.java:191)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.CacheMgmtInterceptor.visitReplaceCommand(CacheMgmtInterceptor.java:182)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:102)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:71)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.AbstractVisitor.visitReplaceCommand(AbstractVisitor.java:54)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:336)
[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.remote.BaseRpcInvokingCommand.processVisitableCommand(BaseRpcInvokingCommand.java:39)
[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.remote.SingleRpcCommand.perform(SingleRpcCommand.java:48)
[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.remoting.inboundhandler.BasePerCacheInboundInvocationHandler.invokePerform(BasePerCacheInboundInvocationHandler.java:85)
[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.remoting.inboundhandler.BaseBlockingRunnable.run(BaseBlockingRunnable.java:32)
[infinispan-embedded-7.2.3.Final.jar:na]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[na:1.8.0_60]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[na:1.8.0_60]
        at java.lang.Thread.run(Thread.java:745) [na:1.8.0_60]
ISPN000071: Caught exception when handling command
SingleRpcCommand{cacheName='sessionStore.sessionMap',
command=ReplaceCommand{key=I9AIaoAPU1N_HqvOeamQ-YZg9qHvo-K2vP6Ksq6t6Yk, oldValue=null,
newValue={"creation_time":1443662394,"sub":"CN-1G56N6E251W11","max_idle":120,"data":{"client_sessions":[{"client_id":"IX-1FXURP1U93W11"}]},"auth_time":1443662394,"amr":["ldap"],"max_life":20160,"auth_life":10080},
metadata=EmbeddedMetadata{version=null}, flags=null, successful=true,
valueMatcher=MATCH_NON_NULL}}
org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after
10 seconds for key I9AIaoAPU1N_HqvOeamQ-YZg9qHvo-K2vP6Ksq6t6Yk and requestor
Thread[remote-thread--p3-t23069,5,main]. Lock is held by
Thread[remote-thread--p3-t23108,5,main], while request came from stage-auth-app02-2214
        at
org.infinispan.util.concurrent.locks.LockManagerImpl.lock(LockManagerImpl.java:198)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.util.concurrent.locks.LockManagerImpl.acquireLockNoCheck(LockManagerImpl.java:181)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:166)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:162)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitNonTxDataWriteCommand(AbstractLockingInterceptor.java:87)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.NonTransactionalLockingInterceptor.visitDataWriteCommand(NonTransactionalLockingInterceptor.java:40)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitReplaceCommand(AbstractLockingInterceptor.java:60)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:111)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.AbstractVisitor.visitReplaceCommand(AbstractVisitor.java:54)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.handleNonTxWriteCommand(StateTransferInterceptor.java:311)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.handleWriteCommand(StateTransferInterceptor.java:249)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.visitReplaceCommand(StateTransferInterceptor.java:128)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.CacheMgmtInterceptor.updateStoreStatistics(CacheMgmtInterceptor.java:191)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.CacheMgmtInterceptor.visitReplaceCommand(CacheMgmtInterceptor.java:182)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:102)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:71)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.AbstractVisitor.visitReplaceCommand(AbstractVisitor.java:54)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:336)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.remote.BaseRpcInvokingCommand.processVisitableCommand(BaseRpcInvokingCommand.java:39)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.remote.SingleRpcCommand.perform(SingleRpcCommand.java:48)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.remoting.inboundhandler.BasePerCacheInboundInvocationHandler.invokePerform(BasePerCacheInboundInvocationHandler.java:85)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.remoting.inboundhandler.BaseBlockingRunnable.run(BaseBlockingRunnable.java:32)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
~[na:1.8.0_60]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
~[na:1.8.0_60]
        at java.lang.Thread.run(Thread.java:745) ~[na:1.8.0_60]}}
}}

was (Author: vdzhuvinov):
We have observed the same exception in logs of a server using Infinispan 7.2.3 in non-tx
replicated cluster:

{{ISPN000136: Execution error
org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after
10 seconds for key I9AIaoAPU1N_HqvOeamQ-YZg9qHvo-K2vP6Ksq6t6Yk and requestor
Thread[remote-thread--p3-t23052,5,main]. Lock is held by
Thread[remote-thread--p3-t23108,5,main], while request came from stage-auth-app02-2214
        at
org.infinispan.util.concurrent.locks.LockManagerImpl.lock(LockManagerImpl.java:198)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.util.concurrent.locks.LockManagerImpl.acquireLockNoCheck(LockManagerImpl.java:181)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:166)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:162)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitNonTxDataWriteCommand(AbstractLockingInterceptor.java:87)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.NonTransactionalLockingInterceptor.visitDataWriteCommand(NonTransactionalLockingInterceptor.java:40)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitReplaceCommand(AbstractLockingInterceptor.java:60)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:111)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.AbstractVisitor.visitReplaceCommand(AbstractVisitor.java:54)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.handleNonTxWriteCommand(StateTransferInterceptor.java:311)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.handleWriteCommand(StateTransferInterceptor.java:249)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.visitReplaceCommand(StateTransferInterceptor.java:128)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.CacheMgmtInterceptor.updateStoreStatistics(CacheMgmtInterceptor.java:191)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.CacheMgmtInterceptor.visitReplaceCommand(CacheMgmtInterceptor.java:182)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:102)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:71)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.AbstractVisitor.visitReplaceCommand(AbstractVisitor.java:54)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:336)
[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.remote.BaseRpcInvokingCommand.processVisitableCommand(BaseRpcInvokingCommand.java:39)
[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.remote.SingleRpcCommand.perform(SingleRpcCommand.java:48)
[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.remoting.inboundhandler.BasePerCacheInboundInvocationHandler.invokePerform(BasePerCacheInboundInvocationHandler.java:85)
[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.remoting.inboundhandler.BaseBlockingRunnable.run(BaseBlockingRunnable.java:32)
[infinispan-embedded-7.2.3.Final.jar:na]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[na:1.8.0_60]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[na:1.8.0_60]
        at java.lang.Thread.run(Thread.java:745) [na:1.8.0_60]
ISPN000071: Caught exception when handling command
SingleRpcCommand{cacheName='sessionStore.sessionMap',
command=ReplaceCommand{key=I9AIaoAPU1N_HqvOeamQ-YZg9qHvo-K2vP6Ksq6t6Yk, oldValue=null,
newValue={"creation_time":1443662394,"sub":"CN-1G56N6E251W11","max_idle":120,"data":{"client_sessions":[{"client_id":"IX-1FXURP1U93W11"}]},"auth_time":1443662394,"amr":["ldap"],"max_life":20160,"auth_life":10080},
metadata=EmbeddedMetadata{version=null}, flags=null, successful=true,
valueMatcher=MATCH_NON_NULL}}
org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after
10 seconds for key I9AIaoAPU1N_HqvOeamQ-YZg9qHvo-K2vP6Ksq6t6Yk and requestor
Thread[remote-thread--p3-t23069,5,main]. Lock is held by
Thread[remote-thread--p3-t23108,5,main], while request came from stage-auth-app02-2214
        at
org.infinispan.util.concurrent.locks.LockManagerImpl.lock(LockManagerImpl.java:198)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.util.concurrent.locks.LockManagerImpl.acquireLockNoCheck(LockManagerImpl.java:181)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:166)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:162)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitNonTxDataWriteCommand(AbstractLockingInterceptor.java:87)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.NonTransactionalLockingInterceptor.visitDataWriteCommand(NonTransactionalLockingInterceptor.java:40)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitReplaceCommand(AbstractLockingInterceptor.java:60)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:111)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.AbstractVisitor.visitReplaceCommand(AbstractVisitor.java:54)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.handleNonTxWriteCommand(StateTransferInterceptor.java:311)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.handleWriteCommand(StateTransferInterceptor.java:249)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.statetransfer.StateTransferInterceptor.visitReplaceCommand(StateTransferInterceptor.java:128)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.CacheMgmtInterceptor.updateStoreStatistics(CacheMgmtInterceptor.java:191)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.CacheMgmtInterceptor.visitReplaceCommand(CacheMgmtInterceptor.java:182)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:97)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:102)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:71)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.AbstractVisitor.visitReplaceCommand(AbstractVisitor.java:54)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.write.ReplaceCommand.acceptVisitor(ReplaceCommand.java:55)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:336)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.remote.BaseRpcInvokingCommand.processVisitableCommand(BaseRpcInvokingCommand.java:39)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.commands.remote.SingleRpcCommand.perform(SingleRpcCommand.java:48)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.remoting.inboundhandler.BasePerCacheInboundInvocationHandler.invokePerform(BasePerCacheInboundInvocationHandler.java:85)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at
org.infinispan.remoting.inboundhandler.BaseBlockingRunnable.run(BaseBlockingRunnable.java:32)
~[infinispan-embedded-7.2.3.Final.jar:na]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
~[na:1.8.0_60]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
~[na:1.8.0_60]
        at java.lang.Thread.run(Thread.java:745) ~[na:1.8.0_60]
}}

...
 Stale locks in non-tx cache after merge
 ---------------------------------------

                 Key: ISPN-4887
                 URL: https://issues.jboss.org/browse/ISPN-4887
             Project: Infinispan
          Issue Type: Bug
          Components: Core, Test Suite - Core
    Affects Versions: 7.0.0.CR2
            Reporter: Dan Berindei
            Assignee: Dan Berindei
            Priority: Blocker
              Labels: testsuite_stability

 It appears to cause random failures in
{{ThreeNodesReplicatedSplitAndMergeTest.testSplitAndMerge0}}:
 {noformat}
 org.infinispan.util.concurrent.TimeoutException: ISPN000299: Unable to acquire lock after
10 seconds for key
MagicKey#null{74043ff7@ThreeNodesReplicatedSplitAndMergeTest-NodeC-12710/9} and requestor
Thread[testng-ThreeNodesReplicatedSplitAndMergeTest,5,main]. Lock is held by
Thread[remote-thread-ThreeNodesReplicatedSplitAndMergeTest-NodeC-p5654-t5,5,main], while
request came from null
     at
org.infinispan.util.concurrent.locks.LockManagerImpl.lock(LockManagerImpl.java:198)
     at
org.infinispan.util.concurrent.locks.LockManagerImpl.acquireLockNoCheck(LockManagerImpl.java:181)
     at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:127)
     at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.lockKey(AbstractLockingInterceptor.java:123)
     at
org.infinispan.interceptors.locking.AbstractLockingInterceptor.visitPutKeyValueCommand(AbstractLockingInterceptor.java:47)
     at
org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:71)
     at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:98)
     at
org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:112)
     at
org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:33)
     at
org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:71)
     at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:98)
     at
org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:112)
     at
org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:33)
     at
org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:71)
     at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:98)
     at
org.infinispan.statetransfer.StateTransferInterceptor.handleNonTxWriteCommand(StateTransferInterceptor.java:172)
     at
org.infinispan.statetransfer.StateTransferInterceptor.visitPutKeyValueCommand(StateTransferInterceptor.java:95)
     at
org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:71)
     at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:98)
     at
org.infinispan.interceptors.CacheMgmtInterceptor.updateStoreStatistics(CacheMgmtInterceptor.java:148)
     at
org.infinispan.interceptors.CacheMgmtInterceptor.visitPutKeyValueCommand(CacheMgmtInterceptor.java:134)
     at
org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:71)
     at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:98)
     at
org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:112)
     at
org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:33)
     at
org.infinispan.partionhandling.impl.PartitionHandlingInterceptor.visitPutKeyValueCommand(PartitionHandlingInterceptor.java:34)
     at
org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:71)
     at
org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:98)
     at
org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:102)
     at
org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:71)
     at
org.infinispan.commands.AbstractVisitor.visitPutKeyValueCommand(AbstractVisitor.java:33)
     at
org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:71)
     at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:333)
     at
org.infinispan.cache.impl.CacheImpl.executeCommandAndCommitIfNeeded(CacheImpl.java:1512)
     at org.infinispan.cache.impl.CacheImpl.putInternal(CacheImpl.java:990)
     at org.infinispan.cache.impl.CacheImpl.put(CacheImpl.java:982)
     at org.infinispan.cache.impl.CacheImpl.put(CacheImpl.java:1582)
     at org.infinispan.cache.impl.CacheImpl.put(CacheImpl.java:235)
     at
org.infinispan.partitionhandling.ThreeNodesReplicatedSplitAndMergeTest.testSplitAndMerge(ThreeNodesReplicatedSplitAndMergeTest.java:132)
     at
org.infinispan.partitionhandling.ThreeNodesReplicatedSplitAndMergeTest.testSplitAndMerge0(ThreeNodesReplicatedSplitAndMergeTest.java:27)
 {noformat}

http://ci.infinispan.org/viewLog.html?buildId=13499&tab=buildResultsD...

--
This message was sent by Atlassian JIRA
(v6.4.11#64026)

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009