4 Replies Latest reply on Mar 25, 2015 11:14 AM by william.burns

    Transaction lock seems not to be released

    maruta.s
      2015-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.