Transaction lock seems not to be released
maruta.s Mar 24, 2015 11:24 AM2015-01-23 06:22:10,626 DEBUG | DSS8062| | IfspnCacheDB| Starting new transaction. |
2015-01-23 06:22:10,626 DEBUG | DSS8062| | IfspnCacheDB| Creating or updating entity: C-UserInfo[AbstractCacheableEntity{replicable=false, isLocalPut=false, isAlreadyDistributed=false}, id=520169, login=cen20169, name=Ivana, surname=Ivanovová, email=iivanovova@csas.cz, ou=40180080, flag=17] |
2015-01-23 06:22:10,626 DEBUG | DSS8062| | IfspnCacheDB| Reading cached entity by keys: C-UserInfo[AbstractCacheableEntity{replicable=false, isLocalPut=false, isAlreadyDistributed=false}, id=520169, login=cen20169, name=Ivana, surname=Ivanovová, email=iivanovova@csas.cz, ou=40180080, flag=17] |
2015-01-23 06:22:10,626 DEBUG | DSS8062| | IfspnCacheDB| Cache entry ce: null |
2015-01-23 06:22:10,626 DEBUG | DSS8062| | IfspnCacheDB| Running query: +useralias_userid:520169 |
2015-01-23 06:22:10,626 DEBUG | JetD-21506| | IfspnCacheDB| Starting new transaction. |
2015-01-23 06:22:10,626 DEBUG | JetD-21506| | IfspnCacheDB| Creating or updating entity: C-UserInfo[AbstractCacheableEntity{replicable=false, isLocalPut=false, isAlreadyDistributed=false}, id=520169, login=cen20169, name=Ivana, surname=Ivanovová, email=iivanovova@csas.cz, ou=40180080, flag=17] |
2015-01-23 06:22:10,626 DEBUG | JetD-21506| | IfspnCacheDB| Reading cached entity by keys: C-UserInfo[AbstractCacheableEntity{replicable=false, isLocalPut=false, isAlreadyDistributed=false}, id=520169, login=cen20169, name=Ivana, surname=Ivanovová, email=iivanovova@csas.cz, ou=40180080, flag=17] |
2015-01-23 06:22:10,626 DEBUG | JetD-21506| | IfspnCacheDB| Cache entry ce: null |
2015-01-23 06:22:10,626 DEBUG | JetD-21506| | IfspnCacheDB| Running query: +useralias_userid:520169 |
2015-01-23 06:22:10,626 DEBUG | DSS8062| | IfspnCacheDB| Running query: +usercard_userid:520169 |
2015-01-23 06:22:10,626 DEBUG | JetD-21506| | IfspnCacheDB| Running query: +usercard_userid:520169 |
2015-01-23 06:22:10,626 DEBUG | DSS8062| | IfspnCacheDB| Running query: +usergroup_userid:520169 |
2015-01-23 06:22:10,642 DEBUG | JetD-21506| | IfspnCacheDB| Running query: +usergroup_userid:520169 |
2015-01-23 06:22:10,657 DEBUG | DSS8062| | IfspnCacheDB| Transaction commit. |
2015-01-23 06:22:10,657 DEBUG | JetD-21506| | IfspnCacheDB| Transaction commit. |
2015-01-23 06:22:40,672 ERROR | DSS8062| tionContextInterceptor| ISPN000136: Execution error |
org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [30 seconds] on key [1000000000491388] for requestor [GlobalTransaction:<null>:4830444:local]! Lock held by [GlobalTransaction:<null>:4830445:local]
at org.infinispan.util.concurrent.locks.LockManagerImpl.lock(LockManagerImpl.java:213)
at org.infinispan.util.concurrent.locks.LockManagerImpl.acquireLock(LockManagerImpl.java:186)
at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.lockKeyAndCheckOwnership(AbstractTxLockingInterceptor.java:187)
at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.lockAndRegisterBackupLock(AbstractTxLockingInterceptor.java:124)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor$LockAcquisitionVisitor.lockAndRecord(OptimisticLockingInterceptor.java:254)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor$LockAcquisitionVisitor.visitSingleKeyCommand(OptimisticLockingInterceptor.java:249)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor$LockAcquisitionVisitor.visitPutKeyValueCommand(OptimisticLockingInterceptor.java:242)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:80)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor.acquireLocksVisitingCommands(OptimisticLockingInterceptor.java:335)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor.visitPrepareCommand(OptimisticLockingInterceptor.java:123)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.NotificationInterceptor.visitPrepareCommand(NotificationInterceptor.java:58)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.invokeNextInterceptorAndVerifyTransaction(TxInterceptor.java:128)
at org.infinispan.interceptors.TxInterceptor.visitPrepareCommand(TxInterceptor.java:115)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:128)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:92)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.BatchingInterceptor.handleDefault(BatchingInterceptor.java:92)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:343)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:141)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:123)
at org.infinispan.transaction.synchronization.SynchronizationAdapter.beforeCompletion(SynchronizationAdapter.java:68)
at org.infinispan.transaction.tm.DummyTransaction.notifyBeforeCompletion(DummyTransaction.java:230)
at org.infinispan.transaction.tm.DummyTransaction.runPrepare(DummyTransaction.java:242)
at org.infinispan.transaction.tm.DummyTransaction.commit(DummyTransaction.java:86)
at org.infinispan.transaction.tm.DummyBaseTransactionManager.commit(DummyBaseTransactionManager.java:102)
at eu.ysoft.cache.ifspn.IfspnCacheDB.commitTransaction(IfspnCacheDB.java:434)
at ysoft.sqcore.user.CacheUserManager.saveUser(CacheUserManager.java:878)
at ysoft.sqcore.user.CacheUserManager.tryToSaveUserFromDTO(CacheUserManager.java:266)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:227)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:177)
at ysoft.sqcore.user.CacheUserManager.syncUserByCard(CacheUserManager.java:136)
at ysoft.sqcore.user.CacheUserManager.getUserByCard(CacheUserManager.java:529)
at ysoft.sqcore.user.AbstractUserManager.getUserByCard(AbstractUserManager.java:55)
at eu.ysoft.ds.user.User.sign(User.java:140)
at eu.ysoft.ds.session.DSSession.validateUser(DSSession.java:475)
at eu.ysoft.ds.session.DSSession.validateUserRequest(DSSession.java:299)
at eu.ysoft.ds.session.DSSession.runActionForMessage(DSSession.java:188)
at eu.ysoft.ds.session.DSSession.run(DSSession.java:148)
at java.lang.Thread.run(Unknown Source)
2015-01-23 06:22:40,672 ERROR | DSS8062| TransactionCoordinator| Error while processing prepare |
org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [30 seconds] on key [1000000000491388] for requestor [GlobalTransaction:<null>:4830444:local]! Lock held by [GlobalTransaction:<null>:4830445:local]
at org.infinispan.util.concurrent.locks.LockManagerImpl.lock(LockManagerImpl.java:213)
at org.infinispan.util.concurrent.locks.LockManagerImpl.acquireLock(LockManagerImpl.java:186)
at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.lockKeyAndCheckOwnership(AbstractTxLockingInterceptor.java:187)
at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.lockAndRegisterBackupLock(AbstractTxLockingInterceptor.java:124)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor$LockAcquisitionVisitor.lockAndRecord(OptimisticLockingInterceptor.java:254)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor$LockAcquisitionVisitor.visitSingleKeyCommand(OptimisticLockingInterceptor.java:249)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor$LockAcquisitionVisitor.visitPutKeyValueCommand(OptimisticLockingInterceptor.java:242)
at org.infinispan.commands.write.PutKeyValueCommand.acceptVisitor(PutKeyValueCommand.java:80)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor.acquireLocksVisitingCommands(OptimisticLockingInterceptor.java:335)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor.visitPrepareCommand(OptimisticLockingInterceptor.java:123)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.NotificationInterceptor.visitPrepareCommand(NotificationInterceptor.java:58)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.invokeNextInterceptorAndVerifyTransaction(TxInterceptor.java:128)
at org.infinispan.interceptors.TxInterceptor.visitPrepareCommand(TxInterceptor.java:115)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:128)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:92)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.BatchingInterceptor.handleDefault(BatchingInterceptor.java:92)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:343)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:141)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:123)
at org.infinispan.transaction.synchronization.SynchronizationAdapter.beforeCompletion(SynchronizationAdapter.java:68)
at org.infinispan.transaction.tm.DummyTransaction.notifyBeforeCompletion(DummyTransaction.java:230)
at org.infinispan.transaction.tm.DummyTransaction.runPrepare(DummyTransaction.java:242)
at org.infinispan.transaction.tm.DummyTransaction.commit(DummyTransaction.java:86)
at org.infinispan.transaction.tm.DummyBaseTransactionManager.commit(DummyBaseTransactionManager.java:102)
at eu.ysoft.cache.ifspn.IfspnCacheDB.commitTransaction(IfspnCacheDB.java:434)
at ysoft.sqcore.user.CacheUserManager.saveUser(CacheUserManager.java:878)
at ysoft.sqcore.user.CacheUserManager.tryToSaveUserFromDTO(CacheUserManager.java:266)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:227)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:177)
at ysoft.sqcore.user.CacheUserManager.syncUserByCard(CacheUserManager.java:136)
at ysoft.sqcore.user.CacheUserManager.getUserByCard(CacheUserManager.java:529)
at ysoft.sqcore.user.AbstractUserManager.getUserByCard(AbstractUserManager.java:55)
at eu.ysoft.ds.user.User.sign(User.java:140)
at eu.ysoft.ds.session.DSSession.validateUser(DSSession.java:475)
at eu.ysoft.ds.session.DSSession.validateUserRequest(DSSession.java:299)
at eu.ysoft.ds.session.DSSession.runActionForMessage(DSSession.java:188)
at eu.ysoft.ds.session.DSSession.run(DSSession.java:148)
at java.lang.Thread.run(Unknown Source)
2015-01-23 06:22:40,688 ERROR | DSS8062| | DummyTransaction| ISPN000109: beforeCompletion() failed for SynchronizationAdapter{localTransaction=LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=null, backupKeyLocks=null, topologyId=-1, isFromStateTransfer=false} org.infinispan.transaction.synchronization.SyncLocalTransaction@49b4ec} org.infinispan.transaction.synchronization.SynchronizationAdapter@49b50b |
org.infinispan.CacheException: Could not prepare.
at org.infinispan.transaction.synchronization.SynchronizationAdapter.beforeCompletion(SynchronizationAdapter.java:70)
at org.infinispan.transaction.tm.DummyTransaction.notifyBeforeCompletion(DummyTransaction.java:230)
at org.infinispan.transaction.tm.DummyTransaction.runPrepare(DummyTransaction.java:242)
at org.infinispan.transaction.tm.DummyTransaction.commit(DummyTransaction.java:86)
at org.infinispan.transaction.tm.DummyBaseTransactionManager.commit(DummyBaseTransactionManager.java:102)
at eu.ysoft.cache.ifspn.IfspnCacheDB.commitTransaction(IfspnCacheDB.java:434)
at ysoft.sqcore.user.CacheUserManager.saveUser(CacheUserManager.java:878)
at ysoft.sqcore.user.CacheUserManager.tryToSaveUserFromDTO(CacheUserManager.java:266)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:227)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:177)
at ysoft.sqcore.user.CacheUserManager.syncUserByCard(CacheUserManager.java:136)
at ysoft.sqcore.user.CacheUserManager.getUserByCard(CacheUserManager.java:529)
at ysoft.sqcore.user.AbstractUserManager.getUserByCard(AbstractUserManager.java:55)
at eu.ysoft.ds.user.User.sign(User.java:140)
at eu.ysoft.ds.session.DSSession.validateUser(DSSession.java:475)
at eu.ysoft.ds.session.DSSession.validateUserRequest(DSSession.java:299)
at eu.ysoft.ds.session.DSSession.runActionForMessage(DSSession.java:188)
at eu.ysoft.ds.session.DSSession.run(DSSession.java:148)
at java.lang.Thread.run(Unknown Source)
Caused by: javax.transaction.xa.XAException
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:161)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:123)
at org.infinispan.transaction.synchronization.SynchronizationAdapter.beforeCompletion(SynchronizationAdapter.java:68)
... 18 more
2015-01-23 06:22:40,688 ERROR | DSS8062| | IfspnCacheDB| Unable to commit transaction: Exception rolled back, status is: 9 |
javax.transaction.RollbackException: Exception rolled back, status is: 9
at org.infinispan.transaction.tm.DummyTransaction.commit(DummyTransaction.java:89)
at org.infinispan.transaction.tm.DummyBaseTransactionManager.commit(DummyBaseTransactionManager.java:102)
at eu.ysoft.cache.ifspn.IfspnCacheDB.commitTransaction(IfspnCacheDB.java:434)
at ysoft.sqcore.user.CacheUserManager.saveUser(CacheUserManager.java:878)
at ysoft.sqcore.user.CacheUserManager.tryToSaveUserFromDTO(CacheUserManager.java:266)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:227)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:177)
at ysoft.sqcore.user.CacheUserManager.syncUserByCard(CacheUserManager.java:136)
at ysoft.sqcore.user.CacheUserManager.getUserByCard(CacheUserManager.java:529)
at ysoft.sqcore.user.AbstractUserManager.getUserByCard(AbstractUserManager.java:55)
at eu.ysoft.ds.user.User.sign(User.java:140)
at eu.ysoft.ds.session.DSSession.validateUser(DSSession.java:475)
at eu.ysoft.ds.session.DSSession.validateUserRequest(DSSession.java:299)
at eu.ysoft.ds.session.DSSession.runActionForMessage(DSSession.java:188)
at eu.ysoft.ds.session.DSSession.run(DSSession.java:148)
at java.lang.Thread.run(Unknown Source)
2015-01-23 06:22:40,688 ERROR | DSS8062| | CacheUserManager| Unable to save user Ivana Ivanovová (cen20169) or related entities. |
java.lang.RuntimeException: Unable to commit transaction: Exception rolled back, status is: 9
at eu.ysoft.cache.ifspn.IfspnCacheDB.commitTransaction(IfspnCacheDB.java:438)
at ysoft.sqcore.user.CacheUserManager.saveUser(CacheUserManager.java:878)
at ysoft.sqcore.user.CacheUserManager.tryToSaveUserFromDTO(CacheUserManager.java:266)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:227)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:177)
at ysoft.sqcore.user.CacheUserManager.syncUserByCard(CacheUserManager.java:136)
at ysoft.sqcore.user.CacheUserManager.getUserByCard(CacheUserManager.java:529)
at ysoft.sqcore.user.AbstractUserManager.getUserByCard(AbstractUserManager.java:55)
at eu.ysoft.ds.user.User.sign(User.java:140)
at eu.ysoft.ds.session.DSSession.validateUser(DSSession.java:475)
at eu.ysoft.ds.session.DSSession.validateUserRequest(DSSession.java:299)
at eu.ysoft.ds.session.DSSession.runActionForMessage(DSSession.java:188)
at eu.ysoft.ds.session.DSSession.run(DSSession.java:148)
at java.lang.Thread.run(Unknown Source)
Caused by: javax.transaction.RollbackException: Exception rolled back, status is: 9
at org.infinispan.transaction.tm.DummyTransaction.commit(DummyTransaction.java:89)
at org.infinispan.transaction.tm.DummyBaseTransactionManager.commit(DummyBaseTransactionManager.java:102)
at eu.ysoft.cache.ifspn.IfspnCacheDB.commitTransaction(IfspnCacheDB.java:434)
... 13 more
2015-01-23 06:22:40,688 DEBUG | DSS8062| | IfspnCacheDB| Transaction rollback. |
2015-01-23 06:22:40,688 ERROR | DSS8062| | IfspnCacheDB| Unable to rollback transaction: no transaction associated with thread |
java.lang.IllegalStateException: no transaction associated with thread
at org.infinispan.transaction.tm.DummyBaseTransactionManager.rollback(DummyBaseTransactionManager.java:124)
at eu.ysoft.cache.ifspn.IfspnCacheDB.rollbackTransaction(IfspnCacheDB.java:445)
at ysoft.sqcore.user.CacheUserManager.saveUser(CacheUserManager.java:881)
at ysoft.sqcore.user.CacheUserManager.tryToSaveUserFromDTO(CacheUserManager.java:266)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:227)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:177)
at ysoft.sqcore.user.CacheUserManager.syncUserByCard(CacheUserManager.java:136)
at ysoft.sqcore.user.CacheUserManager.getUserByCard(CacheUserManager.java:529)
at ysoft.sqcore.user.AbstractUserManager.getUserByCard(AbstractUserManager.java:55)
at eu.ysoft.ds.user.User.sign(User.java:140)
at eu.ysoft.ds.session.DSSession.validateUser(DSSession.java:475)
at eu.ysoft.ds.session.DSSession.validateUserRequest(DSSession.java:299)
at eu.ysoft.ds.session.DSSession.runActionForMessage(DSSession.java:188)
at eu.ysoft.ds.session.DSSession.run(DSSession.java:148)
at java.lang.Thread.run(Unknown Source)
2015-01-23 06:22:40,688 DEBUG | DSS8062| | CacheUserManager| Requesting jobinfo for user: 520169 |
2015-01-23 06:22:40,688 ERROR | JetD-21506| tionContextInterceptor| ISPN000136: Execution error |
org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [30 seconds] on key [1000000000225093] for requestor [GlobalTransaction:<null>:4830447:local]! Lock held by [GlobalTransaction:<null>:4830446:local]
at org.infinispan.util.concurrent.locks.LockManagerImpl.lock(LockManagerImpl.java:213)
at org.infinispan.util.concurrent.locks.LockManagerImpl.acquireLock(LockManagerImpl.java:186)
at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.lockKeyAndCheckOwnership(AbstractTxLockingInterceptor.java:187)
at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.lockAndRegisterBackupLock(AbstractTxLockingInterceptor.java:124)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor.acquireAllLocks(OptimisticLockingInterceptor.java:327)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor.visitPrepareCommand(OptimisticLockingInterceptor.java:132)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.NotificationInterceptor.visitPrepareCommand(NotificationInterceptor.java:58)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.invokeNextInterceptorAndVerifyTransaction(TxInterceptor.java:128)
at org.infinispan.interceptors.TxInterceptor.visitPrepareCommand(TxInterceptor.java:115)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:128)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:92)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.BatchingInterceptor.handleDefault(BatchingInterceptor.java:92)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:343)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:141)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:123)
at org.infinispan.transaction.synchronization.SynchronizationAdapter.beforeCompletion(SynchronizationAdapter.java:68)
at org.infinispan.transaction.tm.DummyTransaction.notifyBeforeCompletion(DummyTransaction.java:230)
at org.infinispan.transaction.tm.DummyTransaction.runPrepare(DummyTransaction.java:242)
at org.infinispan.transaction.tm.DummyTransaction.commit(DummyTransaction.java:86)
at org.infinispan.transaction.tm.DummyBaseTransactionManager.commit(DummyBaseTransactionManager.java:102)
at eu.ysoft.cache.ifspn.IfspnCacheDB.commitTransaction(IfspnCacheDB.java:434)
at ysoft.sqcore.user.CacheUserManager.saveUser(CacheUserManager.java:878)
at ysoft.sqcore.user.CacheUserManager.tryToSaveUserFromDTO(CacheUserManager.java:266)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:227)
at ysoft.sqcore.user.CacheUserManager.syncUserByLogin(CacheUserManager.java:128)
at ysoft.sqcore.user.CacheUserManager.getUserByLogin(CacheUserManager.java:371)
at ysoft.sqcore.user.CacheUserManager.getUserByLogin(CacheUserManager.java:339)
at ysoft.sqpro.spooler.SQJetDirectProcessorAura.setJobOwner(SQJetDirectProcessorAura.java:417)
at ysoft.sqpro.spooler.SQJetDirectProcessorAura.processLine(SQJetDirectProcessorAura.java:578)
at ysoft.sqpro.spooler.SQJetDirectProcessorAura.run(SQJetDirectProcessorAura.java:846)
at ysoft.sqpro.spooler.SQJetDirectSocketAcceptor.run(SQJetDirectSocketAcceptor.java:133)
2015-01-23 06:22:40,688 ERROR | JetD-21506| TransactionCoordinator| Error while processing prepare |
org.infinispan.util.concurrent.TimeoutException: Unable to acquire lock after [30 seconds] on key [1000000000225093] for requestor [GlobalTransaction:<null>:4830447:local]! Lock held by [GlobalTransaction:<null>:4830446:local]
at org.infinispan.util.concurrent.locks.LockManagerImpl.lock(LockManagerImpl.java:213)
at org.infinispan.util.concurrent.locks.LockManagerImpl.acquireLock(LockManagerImpl.java:186)
at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.lockKeyAndCheckOwnership(AbstractTxLockingInterceptor.java:187)
at org.infinispan.interceptors.locking.AbstractTxLockingInterceptor.lockAndRegisterBackupLock(AbstractTxLockingInterceptor.java:124)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor.acquireAllLocks(OptimisticLockingInterceptor.java:327)
at org.infinispan.interceptors.locking.OptimisticLockingInterceptor.visitPrepareCommand(OptimisticLockingInterceptor.java:132)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.NotificationInterceptor.visitPrepareCommand(NotificationInterceptor.java:58)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.TxInterceptor.invokeNextInterceptorAndVerifyTransaction(TxInterceptor.java:128)
at org.infinispan.interceptors.TxInterceptor.visitPrepareCommand(TxInterceptor.java:115)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.base.CommandInterceptor.handleDefault(CommandInterceptor.java:132)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.InvocationContextInterceptor.handleAll(InvocationContextInterceptor.java:128)
at org.infinispan.interceptors.InvocationContextInterceptor.handleDefault(InvocationContextInterceptor.java:92)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.base.CommandInterceptor.invokeNextInterceptor(CommandInterceptor.java:118)
at org.infinispan.interceptors.BatchingInterceptor.handleDefault(BatchingInterceptor.java:92)
at org.infinispan.commands.AbstractVisitor.visitPrepareCommand(AbstractVisitor.java:126)
at org.infinispan.commands.tx.PrepareCommand.acceptVisitor(PrepareCommand.java:124)
at org.infinispan.interceptors.InterceptorChain.invoke(InterceptorChain.java:343)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:141)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:123)
at org.infinispan.transaction.synchronization.SynchronizationAdapter.beforeCompletion(SynchronizationAdapter.java:68)
at org.infinispan.transaction.tm.DummyTransaction.notifyBeforeCompletion(DummyTransaction.java:230)
at org.infinispan.transaction.tm.DummyTransaction.runPrepare(DummyTransaction.java:242)
at org.infinispan.transaction.tm.DummyTransaction.commit(DummyTransaction.java:86)
at org.infinispan.transaction.tm.DummyBaseTransactionManager.commit(DummyBaseTransactionManager.java:102)
at eu.ysoft.cache.ifspn.IfspnCacheDB.commitTransaction(IfspnCacheDB.java:434)
at ysoft.sqcore.user.CacheUserManager.saveUser(CacheUserManager.java:878)
at ysoft.sqcore.user.CacheUserManager.tryToSaveUserFromDTO(CacheUserManager.java:266)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:227)
at ysoft.sqcore.user.CacheUserManager.syncUserByLogin(CacheUserManager.java:128)
at ysoft.sqcore.user.CacheUserManager.getUserByLogin(CacheUserManager.java:371)
at ysoft.sqcore.user.CacheUserManager.getUserByLogin(CacheUserManager.java:339)
at ysoft.sqpro.spooler.SQJetDirectProcessorAura.setJobOwner(SQJetDirectProcessorAura.java:417)
at ysoft.sqpro.spooler.SQJetDirectProcessorAura.processLine(SQJetDirectProcessorAura.java:578)
at ysoft.sqpro.spooler.SQJetDirectProcessorAura.run(SQJetDirectProcessorAura.java:846)
at ysoft.sqpro.spooler.SQJetDirectSocketAcceptor.run(SQJetDirectSocketAcceptor.java:133)
2015-01-23 06:22:40,688 ERROR | JetD-21506| | DummyTransaction| ISPN000109: beforeCompletion() failed for SynchronizationAdapter{localTransaction=LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=null, backupKeyLocks=null, topologyId=-1, isFromStateTransfer=false} org.infinispan.transaction.synchronization.SyncLocalTransaction@49b4ef} org.infinispan.transaction.synchronization.SynchronizationAdapter@49b50e |
org.infinispan.CacheException: Could not prepare.
at org.infinispan.transaction.synchronization.SynchronizationAdapter.beforeCompletion(SynchronizationAdapter.java:70)
at org.infinispan.transaction.tm.DummyTransaction.notifyBeforeCompletion(DummyTransaction.java:230)
at org.infinispan.transaction.tm.DummyTransaction.runPrepare(DummyTransaction.java:242)
at org.infinispan.transaction.tm.DummyTransaction.commit(DummyTransaction.java:86)
at org.infinispan.transaction.tm.DummyBaseTransactionManager.commit(DummyBaseTransactionManager.java:102)
at eu.ysoft.cache.ifspn.IfspnCacheDB.commitTransaction(IfspnCacheDB.java:434)
at ysoft.sqcore.user.CacheUserManager.saveUser(CacheUserManager.java:878)
at ysoft.sqcore.user.CacheUserManager.tryToSaveUserFromDTO(CacheUserManager.java:266)
at ysoft.sqcore.user.CacheUserManager.syncUser(CacheUserManager.java:227)
at ysoft.sqcore.user.CacheUserManager.syncUserByLogin(CacheUserManager.java:128)
at ysoft.sqcore.user.CacheUserManager.getUserByLogin(CacheUserManager.java:371)
at ysoft.sqcore.user.CacheUserManager.getUserByLogin(CacheUserManager.java:339)
at ysoft.sqpro.spooler.SQJetDirectProcessorAura.setJobOwner(SQJetDirectProcessorAura.java:417)
at ysoft.sqpro.spooler.SQJetDirectProcessorAura.processLine(SQJetDirectProcessorAura.java:578)
at ysoft.sqpro.spooler.SQJetDirectProcessorAura.run(SQJetDirectProcessorAura.java:846)
at ysoft.sqpro.spooler.SQJetDirectSocketAcceptor.run(SQJetDirectSocketAcceptor.java:133)
Caused by: javax.transaction.xa.XAException
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:161)
at org.infinispan.transaction.TransactionCoordinator.prepare(TransactionCoordinator.java:123)
at org.infinispan.transaction.synchronization.SynchronizationAdapter.beforeCompletion(SynchronizationAdapter.java:68)
... 15 more
Zprávu upravil(a): Marta Sedlakova I have attached simple test where I succeedd to simulate the problem. I have to set up lockAcquisitionTimeout="30" to invoke thread rollback and after the rollback only one thread is trying to write entities, but never succeed. I have run the test several times and if fails in 75% of invocation.
-
Locks.zip 5.8 KB