gistfile1.txt

10:30:00.108 [main] TRACE o.i.t.t.EmbeddedTransaction - Created new transaction with Xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}
10:30:00.108 [main] TRACE o.i.c.i.CacheImpl - Implicit transaction started! Transaction: EmbeddedTransaction{xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}, status=0}
10:30:00.108 [main] TRACE o.i.t.i.TransactionTable - Created a new local transaction: LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=[], backupKeyLocks=[], topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.synchronization.SyncLocalTransaction@3c
10:30:00.108 [main] TRACE o.i.i.i.InvocationContextInterceptor - Invoked with command PutKeyValueCommand{key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}, flags=[], commandInvocationId=, putIfAbsent=false, valueMatcher=MATCH_ALWAYS, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=null}, successful=true, topologyId=-1} and InvocationContext [org.infinispan.context.impl.LocalTxInvocationContext@8961e55]
10:30:00.108 [main] TRACE o.i.c.m.MarshallableTypeHints - Cache a buffer size predictor for 'org.hawkular.alerts.engine.impl.ispn.model.IspnEvent' assuming its serializability is unknown
10:30:00.108 [main] TRACE o.i.c.m.AdaptiveBufferSizePredictor - Next predicted buffer size for object type 'org.hawkular.alerts.engine.impl.ispn.model.IspnEvent' will be 512
10:30:00.108 [main] TRACE o.i.c.m.MarshallableTypeHints - Cache a buffer size predictor for 'org.hawkular.alerts.engine.impl.ispn.model.IspnEvent' assuming its serializability is unknown
10:30:00.108 [main] TRACE o.i.c.m.AdaptiveBufferSizePredictor - Next predicted buffer size for object type 'org.hawkular.alerts.engine.impl.ispn.model.IspnEvent' will be 512
10:30:00.119 [main] TRACE o.i.c.m.j.AbstractJBossMarshaller - Stop marshaller
10:30:00.119 [main] TRACE o.i.c.m.MarshallableTypeHints - Replacing 'org.hawkular.alerts.engine.impl.ispn.model.IspnEvent' type to be marshallable=true
10:30:00.119 [main] TRACE o.i.t.t.EmbeddedTransaction - Transaction.registerSynchronization(SynchronizationAdapter{localTransaction=LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=[], backupKeyLocks=[], topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.synchronization.SyncLocalTransaction@3c} org.infinispan.transaction.synchronization.SynchronizationAdapter@5b) invoked in transaction with Xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}
10:30:00.119 [main] TRACE o.i.t.t.EmbeddedTransaction - Registering synchronization handler SynchronizationAdapter{localTransaction=LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=[], backupKeyLocks=[], topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.synchronization.SyncLocalTransaction@3c} org.infinispan.transaction.synchronization.SynchronizationAdapter@5b
10:30:00.120 [main] TRACE o.i.c.EntryFactoryImpl - Exists in context? null 
10:30:00.120 [main] TRACE o.i.c.EntryFactoryImpl - Retrieved from container null
10:30:00.120 [main] TRACE o.i.c.EntryFactoryImpl - Creating new entry for key Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14
10:30:00.120 [main] TRACE o.i.c.EntryFactoryImpl - Updated context entry null -> VersionedRepeatableReadEntry(4c57ca10){key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=null, isCreated=true, isChanged=false, isRemoved=false, isValid=true, isExpired=false, skipLookup=false, metadata=EmbeddedMetadata{version=NumericVersion{version=0}}}
10:30:00.120 [main] TRACE o.i.i.i.CacheLoaderInterceptor - Skip load for write command PutKeyValueCommand{key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}, flags=[], commandInvocationId=, putIfAbsent=false, valueMatcher=MATCH_ALWAYS, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=null}, successful=true, topologyId=-1}? false
10:30:00.120 [main] TRACE o.i.p.PersistenceUtil - Loaded null for key Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14 from persistence.
10:30:00.120 [main] TRACE o.i.i.i.CacheLoaderInterceptor - Entry was loaded? false
10:30:00.120 [main] TRACE o.i.i.i.CallInterceptor - Invoking: PutKeyValueCommand
10:30:00.120 [main] TRACE o.i.i.i.EntryWrappingInterceptor - The return value is null
10:30:00.120 [main] TRACE o.i.i.i.EntryWrappingInterceptor - Adding version read NumericVersion{version=0} for key Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14
10:30:00.120 [main] TRACE o.i.t.i.AbstractCacheTransaction - Transaction null:60 read Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14 with version NumericVersion{version=0}
10:30:00.120 [main] TRACE o.i.t.t.EmbeddedTransaction - Transaction.registerSynchronization(org.hibernate.search.backend.impl.PostTransactionWorkQueueSynchronization@3fe21d0e) invoked in transaction with Xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}
10:30:00.120 [main] TRACE o.i.t.t.EmbeddedTransaction - Registering synchronization handler org.hibernate.search.backend.impl.PostTransactionWorkQueueSynchronization@3fe21d0e
10:30:00.120 [main] TRACE o.i.u.c.l.i.DefaultLockManager - Release locks for keys=[]. owner=GlobalTx:local:60
10:30:00.120 [main] TRACE o.i.t.i.AbstractCacheTransaction - Clearing locked keys: []
10:30:00.120 [main] TRACE o.i.t.i.LocalTransaction - Adding modification PutKeyValueCommand{key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}, flags=[], commandInvocationId=, putIfAbsent=false, valueMatcher=MATCH_ALWAYS, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=null}, successful=true, topologyId=-1}. Mod list is null
10:30:00.120 [main] TRACE o.i.c.i.CacheImpl - Committing transaction as it was implicit: EmbeddedTransaction{xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}, status=0}
10:30:00.120 [main] TRACE o.i.t.t.EmbeddedTransaction - Transaction.commit() invoked in transaction with Xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}
10:30:00.120 [main] TRACE o.i.t.t.EmbeddedTransaction - runPrepare() invoked in transaction with Xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}
10:30:00.120 [main] TRACE o.i.t.t.EmbeddedTransaction - Synchronization.beforeCompletion() for SynchronizationAdapter{localTransaction=LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=[], backupKeyLocks=[], topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.synchronization.SyncLocalTransaction@3c} org.infinispan.transaction.synchronization.SynchronizationAdapter@5b
10:30:00.120 [main] TRACE o.i.t.i.TransactionTable - beforeCompletion called for LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=[], backupKeyLocks=[], topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.synchronization.SyncLocalTransaction@3c
10:30:00.120 [main] TRACE o.i.t.i.TransactionCoordinator - Sending prepare command through the chain: VersionedPrepareCommand {modifications=[PutKeyValueCommand{key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}, flags=[], commandInvocationId=, putIfAbsent=false, valueMatcher=MATCH_ALWAYS, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=null}, successful=true, topologyId=-1}], onePhaseCommit=false, retried=false, versionsSeen=null, gtx=GlobalTx:local:60, cacheName='backend'}
10:30:00.120 [main] TRACE o.i.i.i.InvocationContextInterceptor - Invoked with command VersionedPrepareCommand {modifications=[PutKeyValueCommand{key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}, flags=[], commandInvocationId=, putIfAbsent=false, valueMatcher=MATCH_ALWAYS, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=null}, successful=true, topologyId=-1}], onePhaseCommit=false, retried=false, versionsSeen=null, gtx=GlobalTx:local:60, cacheName='backend'} and InvocationContext [org.infinispan.context.impl.LocalTxInvocationContext@787b7796]
10:30:00.120 [main] TRACE o.i.i.l.OptimisticLockingInterceptor - Acquiring locks on Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14.
10:30:00.120 [main] TRACE o.i.t.i.AbstractCacheTransaction - Registering locked key: Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14
10:30:00.120 [main] TRACE o.i.u.c.l.i.DefaultLockManager - Lock key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14 for owner=GlobalTx:local:60. timeout=10000 (MILLISECONDS)
10:30:00.120 [main] TRACE o.i.u.c.l.i.InfinispanLock - Acquire lock for GlobalTx:local:60. Timeout=10000 (MILLISECONDS)
10:30:00.120 [main] TRACE o.i.u.c.l.i.InfinispanLock - Created a new one: LockPlaceHolder{lockState=WAITING, owner=GlobalTx:local:60}
10:30:00.120 [main] TRACE o.i.u.c.l.i.InfinispanLock - Try acquire. Next in queue=LockPlaceHolder{lockState=WAITING, owner=GlobalTx:local:60}. Current=null
10:30:00.120 [main] TRACE o.i.u.c.l.i.InfinispanLock - Lock Owner CAS(null, LockPlaceHolder{lockState=WAITING, owner=GlobalTx:local:60}) => true
10:30:00.120 [main] TRACE o.i.u.c.l.i.InfinispanLock - State changed for LockPlaceHolder{lockState=ACQUIRED, owner=GlobalTx:local:60}. WAITING => ACQUIRED
10:30:00.120 [main] TRACE o.i.u.c.l.i.InfinispanLock - LockPlaceHolder{lockState=ACQUIRED, owner=GlobalTx:local:60} successfully acquired the lock.
10:30:00.120 [main] TRACE o.i.p.PersistenceUtil - Loaded null for key Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14 from persistence.
10:30:00.120 [main] TRACE o.i.c.e.VersionedRepeatableReadEntry - No entry for key Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14 found in data container
10:30:00.120 [main] TRACE o.i.c.e.VersionedRepeatableReadEntry - Comparing versions NumericVersion{version=0} and NumericVersion{version=0} for key Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14: EQUAL
10:30:00.120 [main] TRACE o.i.i.i.CallInterceptor - Invoking: VersionedPrepareCommand
10:30:00.120 [main] TRACE o.i.t.t.EmbeddedTransaction - Synchronization.beforeCompletion() for org.hibernate.search.backend.impl.PostTransactionWorkQueueSynchronization@3fe21d0e
10:30:00.120 [main] TRACE o.h.s.b.i.PostTransactionWorkQueueSynchronization - Processing Transaction's beforeCompletion() phase: org.hibernate.search.backend.impl.PostTransactionWorkQueueSynchronization@3fe21d0e
10:30:00.120 [main] TRACE o.h.s.s.i.PolymorphicIndexHierarchy - Targeted indexed classes for [class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent]: [class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent]
10:30:00.121 [main] TRACE o.i.t.t.EmbeddedTransaction - runCommit(forceRollback=false) invoked in transaction with Xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}
10:30:00.121 [main] TRACE o.i.t.t.EmbeddedTransaction - Synchronization.afterCompletion() for SynchronizationAdapter{localTransaction=LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=[Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14], backupKeyLocks=[], topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.synchronization.SyncLocalTransaction@3c} org.infinispan.transaction.synchronization.SynchronizationAdapter@5b
10:30:00.121 [main] TRACE o.i.t.i.TransactionTable - afterCompletion(3) called for LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=[Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14], backupKeyLocks=[], topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.synchronization.SyncLocalTransaction@3c.
10:30:00.121 [main] TRACE o.i.t.i.TransactionCoordinator - Committing transaction GlobalTx:local:60
10:30:00.121 [main] TRACE o.i.i.i.InvocationContextInterceptor - Invoked with command VersionedCommitCommand{gtx=GlobalTx:local:60, cacheName='backend', topologyId=-1, updatedVersions=null} and InvocationContext [org.infinispan.context.impl.LocalTxInvocationContext@a0e35c3]
10:30:00.121 [main] TRACE o.i.i.i.CacheWriterInterceptor - Calling loader.commit() for transaction GlobalTx:local:60
10:30:00.121 [main] TRACE o.i.t.t.EmbeddedBaseTransactionManager - Suspending tx EmbeddedTransaction{xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}, status=3}
10:30:00.121 [main] TRACE o.i.i.i.CacheWriterInterceptor - Cache loader modification list: [PutKeyValueCommand{key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}, flags=[], commandInvocationId=, putIfAbsent=false, valueMatcher=MATCH_ALWAYS, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=null}, successful=true, topologyId=-1}]
10:30:00.121 [main] TRACE o.i.p.a.AsyncCacheWriter - Queue modification: Store{key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, storedEntry=MarshalledEntryImpl{keyBytes=null, valueBytes=null, metadataBytes=null, key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}, metadata=InternalMetadataImpl{actual=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=NumericVersion{version=33}}, created=-1, lastUsed=-1}, marshaller=org.infinispan.marshall.core.GlobalMarshaller@26f5e45d}}
10:30:00.121 [main] TRACE o.i.t.t.EmbeddedBaseTransactionManager - Resuming tx EmbeddedTransaction{xid=EmbeddedXid{, globalTransactionId = [B0x93E7F7E7D8C69EA5..[64], branchQualifier = [B0x93E7F7E7D8C69EA5..[64]}, status=3}
10:30:00.121 [main] TRACE o.i.i.i.CallInterceptor - Invoking: VersionedCommitCommand
10:30:00.121 [main] TRACE o.i.i.i.EntryWrappingInterceptor - About to commit entry VersionedRepeatableReadEntry(4c57ca10){key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}, isCreated=true, isChanged=true, isRemoved=false, isValid=true, isExpired=false, skipLookup=true, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=NumericVersion{version=0}}}
10:30:00.121 [main] TRACE o.i.s.CommitManager - Trying to commit. Key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14. Operation Flag=null, L1 invalidation=false
10:30:00.121 [main] TRACE o.i.s.CommitManager - Committing key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14. It is a L1 invalidation or a normal put and no tracking is enabled!
10:30:00.121 [main] TRACE o.i.c.e.ReadCommittedEntry - Updating entry (key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14 removed=false valid=true changed=true created=true value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]} metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=NumericVersion{version=0}}, providedMetadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=NumericVersion{version=33}})
10:30:00.121 [main] TRACE o.i.c.DefaultDataContainer - Creating new ICE for writing. Existing=null, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=NumericVersion{version=33}}, new value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}
10:30:00.121 [main] TRACE o.i.c.DefaultDataContainer - Store MetadataImmortalCacheEntry{key=Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14, value=IspnEvent{eventType='ALERT', tenantId='28026b36-8fe4-4332-84c8-524e173a68bf', id='non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14', tags={TriggerTag1Name=TriggerTag1Value, TriggerTag2Name=TriggerTag2Value}, triggerId='non-existence-trigger', ctime=1498206594719, status='OPEN', stime=1498206594719, severity='MEDIUM', category='ALERT', event=Alert [tenantId=28026b36-8fe4-4332-84c8-524e173a68bf, triggerId=non-existence-trigger, severity=MEDIUM, status=OPEN, ctime=1498206594719, lifecycle=[LifeCycle{user='system', status=OPEN, stime=1498206594719}], resolvedEvalSets=null]}, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=-1, version=NumericVersion{version=33}}} in container
10:30:00.121 [AsyncStoreProcessor-backend-2] TRACE o.i.c.m.AdaptiveBufferSizePredictor - Next predicted buffer size for object type 'java.lang.String' will be 128
10:30:00.121 [AsyncStoreProcessor-backend-2] TRACE o.i.c.m.AdaptiveBufferSizePredictor - Next predicted buffer size for object type 'org.hawkular.alerts.engine.impl.ispn.model.IspnEvent' will be 768
10:30:00.121 [main] TRACE o.i.u.c.l.i.DefaultLockManager - Release locks for keys=[Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14]. owner=GlobalTx:local:60
10:30:00.121 [AsyncStoreProcessor-backend-2] TRACE o.i.c.m.AdaptiveBufferSizePredictor - Next predicted buffer size for object type 'org.hawkular.alerts.engine.impl.ispn.model.IspnEvent' will be 512
10:30:00.121 [main] TRACE o.i.u.c.l.i.InfinispanLock - Release lock for GlobalTx:local:60.
10:30:00.121 [main] TRACE o.i.u.c.l.i.InfinispanLock - State changed for LockPlaceHolder{lockState=RELEASED, owner=GlobalTx:local:60}. ACQUIRED => RELEASED
10:30:00.121 [main] TRACE o.i.u.c.l.i.InfinispanLock - Release lock for LockPlaceHolder{lockState=RELEASED, owner=GlobalTx:local:60}? true
10:30:00.121 [main] TRACE o.i.u.c.l.i.InfinispanLock - Try acquire. Next in queue=null. Current=LockPlaceHolder{lockState=RELEASED, owner=GlobalTx:local:60}
10:30:00.121 [main] TRACE o.i.u.c.l.i.InfinispanLock - Lock Owner CAS(LockPlaceHolder{lockState=RELEASED, owner=GlobalTx:local:60}, null) => true
10:30:00.121 [main] TRACE o.i.u.c.l.i.InfinispanLock - Try acquire. Next in queue=null. Current=null
10:30:00.121 [main] TRACE o.i.t.i.AbstractCacheTransaction - Clearing locked keys: [Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14]
10:30:00.121 [main] TRACE o.i.t.i.TransactionTable - Removed LocalTransaction{remoteLockedNodes=null, isMarkedForRollback=false, lockedKeys=[], backupKeyLocks=[], topologyId=-1, stateTransferFlag=null} org.infinispan.transaction.synchronization.SyncLocalTransaction@3c from transaction table.
10:30:00.121 [main] TRACE o.i.t.i.AbstractCacheTransaction - Transaction GlobalTx:local:60 has completed, notifying listening threads.
10:30:00.121 [main] TRACE o.i.t.i.TransactionTable - Committed in onePhase? false isOptimistic? true
10:30:00.121 [main] TRACE o.i.t.t.EmbeddedTransaction - Synchronization.afterCompletion() for org.hibernate.search.backend.impl.PostTransactionWorkQueueSynchronization@3fe21d0e
10:30:00.121 [main] TRACE o.h.s.b.i.PostTransactionWorkQueueSynchronization - Processing Transaction's afterCompletion() phase for org.hibernate.search.backend.impl.PostTransactionWorkQueueSynchronization@3fe21d0e. Performing work.
10:30:00.121 [main] TRACE o.h.s.b.i.BatchedQueueingProcessor - Lucene WorkQueue to send to backends:[ 
	UpdateLuceneWork: org.hawkular.alerts.engine.impl.ispn.model.IspnEvent#S:Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14
]
10:30:00.121 [main] DEBUG o.h.s.i.s.DirectoryBasedIndexManager - Applying work via workspace holder of type class org.hibernate.search.backend.impl.lucene.WorkspaceHolder
10:30:00.121 [AsyncStoreProcessor-backend-2] TRACE o.i.c.m.j.AbstractJBossMarshaller - Stop marshaller
10:30:00.121 [AsyncStoreProcessor-backend-2] TRACE o.i.c.m.AdaptiveBufferSizePredictor - Next predicted buffer size for object type 'org.infinispan.metadata.impl.InternalMetadataImpl' will be 128
10:30:00.122 [AsyncStoreProcessor-backend-2] TRACE o.i.p.f.SingleFileStore - New entry allocated at 29150:1986, 0 free entries, file size is 31136
10:30:00.122 [AsyncStoreProcessor-backend-2] TRACE o.i.p.f.SingleFileStore - Wrote entry Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14:1986 at 29150:1986
10:30:00.122 [Hibernate Search sync consumer thread for index event] TRACE o.h.s.b.i.l.IndexWriterHolder - IndexWriter opened
10:30:00.122 [Hibernate Search sync consumer thread for index event] TRACE o.h.s.b.i.l.w.ByTermUpdateWorkExecutor - Updating class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent#S:Event-28026b36-8fe4-4332-84c8-524e173a68bf-non-existence-trigger-1498206594719-de05162d-5f26-437d-94ca-664e5574fb14 by id using an IndexWriter#updateDocument.
10:30:01.712 [main] TRACE o.i.i.i.InvocationContextInterceptor - Invoked with command GetKeyValueCommand {key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}, flags=[]} and InvocationContext [org.infinispan.context.SingleKeyNonTxInvocationContext@70c99e13]
10:30:01.712 [main] TRACE o.i.c.EntryFactoryImpl - Exists in context? null 
10:30:01.713 [main] TRACE o.i.c.EntryFactoryImpl - Retrieved from container null
10:30:01.713 [main] TRACE o.i.c.EntryFactoryImpl - Wrap QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult} for read. Entry=NullCacheEntry{}
10:30:01.713 [main] TRACE o.i.i.i.CallInterceptor - Invoking: GetKeyValueCommand
10:30:01.790 [main] TRACE o.i.i.i.InvocationContextInterceptor - Invoked with command PutKeyValueCommand{key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}, value=FilterParsingResult [ queryString=from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) , targetEntityName=org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, parameterNames=[], whereClause=AND(AND(EQUAL(PROP(eventType), CONST("ALERT")), EQUAL(PROP(tenantId), CONST("28026b36-8fe4-4332-84c8-524e173a68bf"))), AND(PROP(tags*):'TriggerTag1Name', PROP(tags*):'TriggerTag1Value')), havingClause=null, projectedPaths=null, projectedTypes=null, groupBy=null, sortFields=null], flags=[], commandInvocationId=CommandInvocation:<local>:86, putIfAbsent=true, valueMatcher=MATCH_EXPECTED, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null}, successful=true, topologyId=-1} and InvocationContext [org.infinispan.context.SingleKeyNonTxInvocationContext@29811d4d]
10:30:01.791 [main] TRACE o.i.i.l.NonTransactionalLockingInterceptor - Are (<local>) we the lock owners for key 'QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}'? true
10:30:01.791 [main] TRACE o.i.u.c.l.i.DefaultLockManager - Lock key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult} for owner=CommandInvocation:<local>:86. timeout=10000 (MILLISECONDS)
10:30:01.792 [main] TRACE o.i.u.c.l.i.InfinispanLock - Acquire lock for CommandInvocation:<local>:86. Timeout=10000 (MILLISECONDS)
10:30:01.792 [main] TRACE o.i.u.c.l.i.InfinispanLock - Created a new one: LockPlaceHolder{lockState=WAITING, owner=CommandInvocation:<local>:86}
10:30:01.793 [main] TRACE o.i.u.c.l.i.InfinispanLock - Try acquire. Next in queue=LockPlaceHolder{lockState=WAITING, owner=CommandInvocation:<local>:86}. Current=null
10:30:01.793 [main] TRACE o.i.u.c.l.i.InfinispanLock - Lock Owner CAS(null, LockPlaceHolder{lockState=WAITING, owner=CommandInvocation:<local>:86}) => true
10:30:01.794 [main] TRACE o.i.u.c.l.i.InfinispanLock - State changed for LockPlaceHolder{lockState=ACQUIRED, owner=CommandInvocation:<local>:86}. WAITING => ACQUIRED
10:30:01.794 [main] TRACE o.i.u.c.l.i.InfinispanLock - LockPlaceHolder{lockState=ACQUIRED, owner=CommandInvocation:<local>:86} successfully acquired the lock.
10:30:01.794 [main] TRACE o.i.c.EntryFactoryImpl - Exists in context? null 
10:30:01.795 [main] TRACE o.i.c.EntryFactoryImpl - Retrieved from container null
10:30:01.795 [main] TRACE o.i.c.EntryFactoryImpl - Creating new entry for key QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}
10:30:01.796 [main] TRACE o.i.c.EntryFactoryImpl - Updated context entry null -> ReadCommittedEntry(28f081ad){key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}, value=null, isCreated=true, isChanged=false, isRemoved=false, isValid=true, isExpired=false, skipLookup=false, metadata=null}
10:30:01.796 [main] TRACE o.i.i.i.CallInterceptor - Invoking: PutKeyValueCommand
10:30:01.797 [main] TRACE o.i.i.i.EntryWrappingInterceptor - About to commit entry ReadCommittedEntry(28f081ad){key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}, value=FilterParsingResult [ queryString=from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) , targetEntityName=org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, parameterNames=[], whereClause=AND(AND(EQUAL(PROP(eventType), CONST("ALERT")), EQUAL(PROP(tenantId), CONST("28026b36-8fe4-4332-84c8-524e173a68bf"))), AND(PROP(tags*):'TriggerTag1Name', PROP(tags*):'TriggerTag1Value')), havingClause=null, projectedPaths=null, projectedTypes=null, groupBy=null, sortFields=null], isCreated=true, isChanged=true, isRemoved=false, isValid=true, isExpired=false, skipLookup=false, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null}}
10:30:01.797 [main] TRACE o.i.s.CommitManager - Trying to commit. Key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}. Operation Flag=null, L1 invalidation=false
10:30:01.798 [main] TRACE o.i.s.CommitManager - Committing key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}. It is a L1 invalidation or a normal put and no tracking is enabled!
10:30:01.799 [main] TRACE o.i.c.e.ReadCommittedEntry - Updating entry (key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult} removed=false valid=true changed=true created=true value=FilterParsingResult [ queryString=from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) , targetEntityName=org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, parameterNames=[], whereClause=AND(AND(EQUAL(PROP(eventType), CONST("ALERT")), EQUAL(PROP(tenantId), CONST("28026b36-8fe4-4332-84c8-524e173a68bf"))), AND(PROP(tags*):'TriggerTag1Name', PROP(tags*):'TriggerTag1Value')), havingClause=null, projectedPaths=null, projectedTypes=null, groupBy=null, sortFields=null] metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null}, providedMetadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null})
10:30:01.800 [main] TRACE o.i.c.DefaultDataContainer - Creating new ICE for writing. Existing=null, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null}, new value=FilterParsingResult [ queryString=from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) , targetEntityName=org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, parameterNames=[], whereClause=AND(AND(EQUAL(PROP(eventType), CONST("ALERT")), EQUAL(PROP(tenantId), CONST("28026b36-8fe4-4332-84c8-524e173a68bf"))), AND(PROP(tags*):'TriggerTag1Name', PROP(tags*):'TriggerTag1Value')), havingClause=null, projectedPaths=null, projectedTypes=null, groupBy=null, sortFields=null]
10:30:01.801 [main] TRACE o.i.c.DefaultDataContainer - Store TransientCacheEntry{key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}, value=FilterParsingResult [ queryString=from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) , targetEntityName=org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, parameterNames=[], whereClause=AND(AND(EQUAL(PROP(eventType), CONST("ALERT")), EQUAL(PROP(tenantId), CONST("28026b36-8fe4-4332-84c8-524e173a68bf"))), AND(PROP(tags*):'TriggerTag1Name', PROP(tags*):'TriggerTag1Value')), havingClause=null, projectedPaths=null, projectedTypes=null, groupBy=null, sortFields=null]} in container
10:30:01.801 [main] TRACE o.i.u.c.l.i.DefaultLockManager - Release locks for keys=[QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.objectfilter.impl.syntax.parser.IckleParsingResult}]. owner=CommandInvocation:<local>:86
10:30:01.802 [main] TRACE o.i.u.c.l.i.InfinispanLock - Release lock for CommandInvocation:<local>:86.
10:30:01.802 [main] TRACE o.i.u.c.l.i.InfinispanLock - State changed for LockPlaceHolder{lockState=RELEASED, owner=CommandInvocation:<local>:86}. ACQUIRED => RELEASED
10:30:01.803 [main] TRACE o.i.u.c.l.i.InfinispanLock - Release lock for LockPlaceHolder{lockState=RELEASED, owner=CommandInvocation:<local>:86}? true
10:30:01.803 [main] TRACE o.i.u.c.l.i.InfinispanLock - Try acquire. Next in queue=null. Current=LockPlaceHolder{lockState=RELEASED, owner=CommandInvocation:<local>:86}
10:30:01.804 [main] TRACE o.i.u.c.l.i.InfinispanLock - Lock Owner CAS(LockPlaceHolder{lockState=RELEASED, owner=CommandInvocation:<local>:86}, null) => true
10:30:01.804 [main] TRACE o.i.u.c.l.i.InfinispanLock - Try acquire. Next in queue=null. Current=null
10:30:01.805 [main] DEBUG o.i.q.d.e.i.QueryEngine - Building query 'from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ' with parameters null
10:30:01.806 [main] DEBUG o.i.q.d.e.i.QueryEngine - Building Lucene query for : from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) 
10:30:01.806 [main] TRACE o.i.i.i.InvocationContextInterceptor - Invoked with command GetKeyValueCommand {key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}, flags=[]} and InvocationContext [org.infinispan.context.SingleKeyNonTxInvocationContext@56fc2cea]
10:30:01.807 [main] TRACE o.i.c.EntryFactoryImpl - Exists in context? null 
10:30:01.807 [main] TRACE o.i.c.EntryFactoryImpl - Retrieved from container null
10:30:01.807 [main] TRACE o.i.c.EntryFactoryImpl - Wrap QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult} for read. Entry=NullCacheEntry{}
10:30:01.808 [main] TRACE o.i.i.i.CallInterceptor - Invoking: GetKeyValueCommand
10:30:01.819 [main] TRACE o.i.i.i.InvocationContextInterceptor - Invoked with command PutKeyValueCommand{key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}, value=LuceneQueryParsingResult [query=+(+eventType:ALERT +tenantId:28026b36-8fe4-4332-84c8-524e173a68bf) +(+tags:triggertag1name +tags:triggertag1value), targetEntityMetadata=class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, projections=null, sort=null], flags=[], commandInvocationId=CommandInvocation:<local>:87, putIfAbsent=true, valueMatcher=MATCH_EXPECTED, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null}, successful=true, topologyId=-1} and InvocationContext [org.infinispan.context.SingleKeyNonTxInvocationContext@45430a27]
10:30:01.820 [main] TRACE o.i.i.l.NonTransactionalLockingInterceptor - Are (<local>) we the lock owners for key 'QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}'? true
10:30:01.821 [main] TRACE o.i.u.c.l.i.DefaultLockManager - Lock key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult} for owner=CommandInvocation:<local>:87. timeout=10000 (MILLISECONDS)
10:30:01.821 [main] TRACE o.i.u.c.l.i.InfinispanLock - Acquire lock for CommandInvocation:<local>:87. Timeout=10000 (MILLISECONDS)
10:30:01.822 [main] TRACE o.i.u.c.l.i.InfinispanLock - Created a new one: LockPlaceHolder{lockState=WAITING, owner=CommandInvocation:<local>:87}
10:30:01.822 [main] TRACE o.i.u.c.l.i.InfinispanLock - Try acquire. Next in queue=LockPlaceHolder{lockState=WAITING, owner=CommandInvocation:<local>:87}. Current=null
10:30:01.822 [main] TRACE o.i.u.c.l.i.InfinispanLock - Lock Owner CAS(null, LockPlaceHolder{lockState=WAITING, owner=CommandInvocation:<local>:87}) => true
10:30:01.823 [main] TRACE o.i.u.c.l.i.InfinispanLock - State changed for LockPlaceHolder{lockState=ACQUIRED, owner=CommandInvocation:<local>:87}. WAITING => ACQUIRED
10:30:01.823 [main] TRACE o.i.u.c.l.i.InfinispanLock - LockPlaceHolder{lockState=ACQUIRED, owner=CommandInvocation:<local>:87} successfully acquired the lock.
10:30:01.824 [main] TRACE o.i.c.EntryFactoryImpl - Exists in context? null 
10:30:01.824 [main] TRACE o.i.c.EntryFactoryImpl - Retrieved from container null
10:30:01.824 [main] TRACE o.i.c.EntryFactoryImpl - Creating new entry for key QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}
10:30:01.825 [main] TRACE o.i.c.EntryFactoryImpl - Updated context entry null -> ReadCommittedEntry(1b83fac3){key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}, value=null, isCreated=true, isChanged=false, isRemoved=false, isValid=true, isExpired=false, skipLookup=false, metadata=null}
10:30:01.825 [main] TRACE o.i.i.i.CallInterceptor - Invoking: PutKeyValueCommand
10:30:01.826 [main] TRACE o.i.i.i.EntryWrappingInterceptor - About to commit entry ReadCommittedEntry(1b83fac3){key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}, value=LuceneQueryParsingResult [query=+(+eventType:ALERT +tenantId:28026b36-8fe4-4332-84c8-524e173a68bf) +(+tags:triggertag1name +tags:triggertag1value), targetEntityMetadata=class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, projections=null, sort=null], isCreated=true, isChanged=true, isRemoved=false, isValid=true, isExpired=false, skipLookup=false, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null}}
10:30:01.827 [main] TRACE o.i.s.CommitManager - Trying to commit. Key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}. Operation Flag=null, L1 invalidation=false
10:30:01.827 [main] TRACE o.i.s.CommitManager - Committing key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}. It is a L1 invalidation or a normal put and no tracking is enabled!
10:30:01.829 [main] TRACE o.i.c.e.ReadCommittedEntry - Updating entry (key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult} removed=false valid=true changed=true created=true value=LuceneQueryParsingResult [query=+(+eventType:ALERT +tenantId:28026b36-8fe4-4332-84c8-524e173a68bf) +(+tags:triggertag1name +tags:triggertag1value), targetEntityMetadata=class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, projections=null, sort=null] metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null}, providedMetadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null})
10:30:01.829 [main] TRACE o.i.c.DefaultDataContainer - Creating new ICE for writing. Existing=null, metadata=EmbeddedExpirableMetadata{lifespan=-1, maxIdle=300000, version=null}, new value=LuceneQueryParsingResult [query=+(+eventType:ALERT +tenantId:28026b36-8fe4-4332-84c8-524e173a68bf) +(+tags:triggertag1name +tags:triggertag1value), targetEntityMetadata=class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, projections=null, sort=null]
10:30:01.830 [main] TRACE o.i.c.DefaultDataContainer - Store TransientCacheEntry{key=QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}, value=LuceneQueryParsingResult [query=+(+eventType:ALERT +tenantId:28026b36-8fe4-4332-84c8-524e173a68bf) +(+tags:triggertag1name +tags:triggertag1value), targetEntityMetadata=class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent, projections=null, sort=null]} in container
10:30:01.831 [main] TRACE o.i.u.c.l.i.DefaultLockManager - Release locks for keys=[QueryCacheKey{queryString='from org.hawkular.alerts.engine.impl.ispn.model.IspnEvent where eventType = 'ALERT' and (tenantId = '28026b36-8fe4-4332-84c8-524e173a68bf' ) and (tags : ('TriggerTag1Name' and ('TriggerTag1Value'))) ', accumulators=null, queryTypeDiscriminator=class org.infinispan.query.dsl.embedded.impl.LuceneQueryParsingResult}]. owner=CommandInvocation:<local>:87
10:30:01.831 [main] TRACE o.i.u.c.l.i.InfinispanLock - Release lock for CommandInvocation:<local>:87.
10:30:01.832 [main] TRACE o.i.u.c.l.i.InfinispanLock - State changed for LockPlaceHolder{lockState=RELEASED, owner=CommandInvocation:<local>:87}. ACQUIRED => RELEASED
10:30:01.832 [main] TRACE o.i.u.c.l.i.InfinispanLock - Release lock for LockPlaceHolder{lockState=RELEASED, owner=CommandInvocation:<local>:87}? true
10:30:01.833 [main] TRACE o.i.u.c.l.i.InfinispanLock - Try acquire. Next in queue=null. Current=LockPlaceHolder{lockState=RELEASED, owner=CommandInvocation:<local>:87}
10:30:01.833 [main] TRACE o.i.u.c.l.i.InfinispanLock - Lock Owner CAS(LockPlaceHolder{lockState=RELEASED, owner=CommandInvocation:<local>:87}, null) => true
10:30:01.834 [main] TRACE o.i.u.c.l.i.InfinispanLock - Try acquire. Next in queue=null. Current=null
10:30:01.834 [main] DEBUG o.i.q.d.e.i.QueryEngine - The resulting Lucene query is : +(+eventType:ALERT +tenantId:28026b36-8fe4-4332-84c8-524e173a68bf) +(+tags:triggertag1name +tags:triggertag1value)
10:30:01.835 [main] TRACE o.h.s.s.i.PolymorphicIndexHierarchy - Targeted indexed classes for [class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent]: [class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent]
10:30:01.835 [main] TRACE o.h.s.s.i.PolymorphicIndexHierarchy - Targeted indexed classes for [class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent]: [class org.hawkular.alerts.engine.impl.ispn.model.IspnEvent]
10:30:01.871 [main] DEBUG o.h.s.fulltext_query - HSEARCH000274: Executing Lucene query '+(+eventType:ALERT +tenantId:28026b36-8fe4-4332-84c8-524e173a68bf) +(+tags:triggertag1name +tags:triggertag1value)'
10:30:01.874 [main] DEBUG o.h.s.r.i.ManagedMultiReader - Closing MultiReader: ManagedMultiReader [readersForClosing=[StandardDirectoryReader(segments_1:4:nrt _0(5.5.4):c1)], readerProviders=[org.hibernate.search.backend.impl.lucene.NRTWorkspaceImpl@2ddb6c71]]
10:30:01.874 [main] TRACE o.h.s.r.i.ManagedMultiReader - MultiReader closed.
10:30:01.874 [main] TRACE o.i.i.i.InvocationContextInterceptor - Invoked with command GetAllCommand{keys=[], returnEntries=false, flags=[]} and InvocationContext [org.infinispan.context.impl.NonTxInvocationContext@2b6a4da7]
10:30:01.875 [main] TRACE o.i.i.i.CallInterceptor - Invoking: GetAllCommand
10:30:01.875 [main] TRACE o.i.i.i.EntryWrappingInterceptor - Notifying getAll? true; result {}
10:30:01.875 [main] TRACE o.i.u.c.l.i.DefaultLockManager - Release locks for keys=[]. owner=null