Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/119/
1 tests failed.
FAILED: org.apache.solr.search.TestStressRecovery.testStressRecovery
Error Message:
Captured an uncaught exception in thread: Thread[id=485, name=READER3,
state=RUNNABLE, group=TGRP-TestStressRecovery]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=485, name=READER3, state=RUNNABLE,
group=TGRP-TestStressRecovery]
at
__randomizedtesting.SeedInfo.seed([9E66594D2DC14A4:B3DC0CC94D34ABAA]:0)
Caused by: java.lang.RuntimeException: java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([9E66594D2DC14A4]:0)
at
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:331)
Caused by: java.lang.AssertionError
at org.junit.Assert.fail(Assert.java:92)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertTrue(Assert.java:54)
at
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:318)
Build Log:
[...truncated 10727 lines...]
[junit4] Suite: org.apache.solr.search.TestStressRecovery
[junit4] 2> Creating dataDir:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001
[junit4] 2> 88166 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 88166 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 88166 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1'
[junit4] 2> 88166 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 88166 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader using system property solr.solr.home:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr
[junit4] 2> 88166 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes/'
to classloader
[junit4] 2> 88166 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/README'
to classloader
[junit4] 2> 88213 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 88221 WARN
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ] o.a.s.c.Config
Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory>
instead.
[junit4] 2> 88221 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 88248 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 88268 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 88921 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 88922 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 88922 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 88929 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 88934 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 88934 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader using system property solr.solr.home:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr
[junit4] 2> 88934 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr'
[junit4] 2> 88934 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 88934 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.SolrResourceLoader using system property solr.solr.home:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr
[junit4] 2> 88934 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.CoreContainer New CoreContainer 1296582111
[junit4] 2> 88934 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.CoreContainer Loading cores into CoreContainer
[instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr]
[junit4] 2> 88934 WARN
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.CoreContainer Couldn't add files from
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/lib
to classpath:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/lib
[junit4] 2> 88935 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout :
600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections :
10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime :
5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 88937 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params:
socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 88937 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 88937 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.l.LogWatcher Registering Log Listener [Log4j
(org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 88938 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for
authorization module.
[junit4] 2> 88938 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 88938 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1,
config=solrconfig-tlog.xml, transient=false, schema=schema15.xml,
loadOnStartup=true, configSetProperties=configsetprops.json,
collection=collection1,
dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001,
shard=shard1}
[junit4] 2> 88938 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory:
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1'
[junit4] 2> 88945 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 88945 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.SolrResourceLoader using system property solr.solr.home:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr
[junit4] 2> 88945 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes/'
to classloader
[junit4] 2> 88945 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.SolrResourceLoader Adding
'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/README'
to classloader
[junit4] 2> 88961 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 88963 WARN (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use
<mergePolicyFactory> instead.
[junit4] 2> 88964 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0
[junit4] 2> 88971 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-tlog.xml
[junit4] 2> 88975 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 89192 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 89192 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 89193 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 89200 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 89220 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default
properties: Can't find resource 'configsetprops.json' in classpath or
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1'
[junit4] 2> 89220 INFO (coreLoadExecutor-296-thread-1) [ ]
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from
instancedir
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1
[junit4] 2> 89220 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 89220 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/]
[junit4] 2> 89220 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@332ca81b
[junit4] 2> 89235 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001
[junit4] 2> 89237 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrCore New index directory detected: old=null
new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/index/
[junit4] 2> 89237 WARN (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory
'/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/index'
doesn't exist. Creating new index...
[junit4] 2> 89237 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/index
[junit4] 2> 89237 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=45, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=0.92578125,
floorSegmentMB=0.76171875, forceMergeDeletesPctAllowed=21.140509819697225,
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 89251 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_1,generation=1}
[junit4] 2> 89251 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 89268 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "nodistrib"
[junit4] 2> 89268 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "dedupe"
[junit4] 2> 89268 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 89268 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "stored_sig"
[junit4] 2> 89268 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 89268 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 89269 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating
updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 89269 INFO
(OldIndexDirectoryCleanupThreadForCore-collection1) [ x:collection1]
o.a.s.c.SolrCore Looking for old index directories to cleanup for core
collection1 in
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/
[junit4] 2> 89270 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting
DistributedUpdateProcessorFactory into updateRequestProcessorChain
"distrib-dup-test-chain-implicit"
[junit4] 2> 89270 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as
default, creating implicit default
[junit4] 2> 89270 WARN (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler
{type = requestHandler,name = /dump,class = DumpRequestHandler,args =
{defaults={a=A,b=B}}}
[junit4] 2> 89271 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 89272 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 89273 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 89273 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 89295 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.RequestHandlers Registered paths:
/admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file
[junit4] 2> 89303 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrCore Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 89303 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 89303 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10
numVersionBuckets=65536
[junit4] 2> 89304 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 89304 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 89304 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy:
maxMergeAtOnce=46, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=3.8173828125,
floorSegmentMB=1.25, forceMergeDeletesPctAllowed=8.264848930349793,
segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 89312 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits:
num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_1,generation=1}
[junit4] 2> 89312 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
[junit4] 2> 89312 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.s.SolrIndexSearcher Opening
[Searcher@7dd02bb8[collection1] main]
[junit4] 2> 89313 WARN (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/conf;
switching to use InMemory storage instead.
[junit4] 2> 89313 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 89313 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using
InMemoryStorage
[junit4] 2> 89313 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 89313 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 89313 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed
version buckets
[junit4] 2> 89313 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for
65536 version buckets from index
[junit4] 2> 89313 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed
version bucket highest value from index
[junit4] 2> 89313 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent
updates, using new clock 1530865980964601856
[junit4] 2> 89314 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with
highest version 1530865980964601856
[junit4] 2> 89315 INFO (coreLoadExecutor-296-thread-1) [
x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 89315 INFO
(searcherExecutor-297-thread-1-processing-x:collection1) [ x:collection1]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@7dd02bb8[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 89316 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 89324 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testStressRecovery
[junit4] 2> 89333 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 89351 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@270df58f[collection1] realtime]
[junit4] 2> 89351 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
(-9223372036854775807)} 0 26
[junit4] 2> 89353 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 89364 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_2,generation=2}
[junit4] 2> 89365 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.c.SolrDeletionPolicy newest commit generation = 2
[junit4] 2> 89365 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@125fc4ad[collection1] main]
[junit4] 2> 89365 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 89366 INFO (searcherExecutor-297-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@125fc4ad[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 89367 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 13
[junit4] 2> 89372 INFO (WRITER0) [ ] o.a.s.u.DirectUpdateHandler2
start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 89373 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=46&wt=json} status=0 QTime=0
[junit4] 2> 89375 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 89375 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89375 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89379 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=152&wt=json} status=0 QTime=0
[junit4] 2> 89390 INFO (WRITER0) [ ] o.a.s.u.DirectUpdateHandler2 No
uncommitted changes. Skipping IW.commit.
[junit4] 2> 89390 INFO (WRITER0) [ ] o.a.s.c.SolrCore
SolrIndexSearcher has not changed - not re-opening:
org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 89390 INFO (WRITER0) [ ] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 89391 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-1&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-1)]} 0 19
[junit4] 2> 89392 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:51&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89393 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89393 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=116&wt=json} status=0 QTime=0
[junit4] 2> 89393 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=133&wt=json} status=0 QTime=0
[junit4] 2> 89393 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:44&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89393 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:114&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89393 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
[junit4] 2> 89394 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=135&wt=json} status=0 QTime=0
[junit4] 2> 89394 INFO (WRITER0) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 22
[junit4] 2> 89394 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89394 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=145&wt=json} status=0 QTime=0
[junit4] 2> 89394 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
[junit4] 2> 89396 INFO (WRITER3) [ ] o.a.s.u.DirectUpdateHandler2
start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 89439 INFO (WRITER4) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-2&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-2)]} 0 66
[junit4] 2> 89440 INFO (WRITER0) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-3&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-3)]} 0 44
[junit4] 2> 89440 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=45
[junit4] 2> 89441 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89441 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89441 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=124&wt=json} status=0 QTime=0
[junit4] 2> 89441 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=46
[junit4] 2> 89442 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=47
[junit4] 2> 89442 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 89442 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=158&wt=json} status=0 QTime=0
[junit4] 2> 89442 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=155&wt=json} status=0 QTime=0
[junit4] 2> 89442 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=50
[junit4] 2> 89442 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89443 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=77&wt=json} status=0 QTime=0
[junit4] 2> 89455 INFO (WRITER3) [ ] o.a.s.c.SolrDeletionPolicy
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_3,generation=3}
[junit4] 2> 89455 INFO (WRITER3) [ ] o.a.s.c.SolrDeletionPolicy newest
commit generation = 3
[junit4] 2> 89456 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher Opening
[Searcher@6002f2e4[collection1] main]
[junit4] 2> 89456 INFO (WRITER3) [ ] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 89457 INFO (searcherExecutor-297-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@6002f2e4[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 89457 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 61
[junit4] 2> 89459 INFO (WRITER2) [ ] o.a.s.u.DirectUpdateHandler2
start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 89459 INFO (WRITER2) [ ] o.a.s.u.DirectUpdateHandler2 No
uncommitted changes. Skipping IW.commit.
[junit4] 2> 89459 INFO (WRITER2) [ ] o.a.s.c.SolrCore
SolrIndexSearcher has not changed - not re-opening:
org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 89459 INFO (WRITER2) [ ] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 89459 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89461 INFO (WRITER1) [ ] o.a.s.u.DirectUpdateHandler2
start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 89461 INFO (WRITER1) [ ] o.a.s.c.SolrCore
SolrIndexSearcher has not changed - not re-opening:
org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 89461 INFO (WRITER1) [ ] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 89461 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89462 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 89462 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 89462 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 89462 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 89473 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 89473 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=null}
[junit4] 2> 89476 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89476 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89476 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:59&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89476 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89476 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
[junit4] 2> 89476 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
[junit4] 2> 89476 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89476 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89476 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
[junit4] 2> 89476 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89477 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=122&wt=json} status=0 QTime=0
[junit4] 2> 89477 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89477 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89477 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89477 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=153&wt=json} status=0 QTime=0
[junit4] 2> 89477 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89477 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=142&wt=json} status=0 QTime=0
[junit4] 2> 89477 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:82&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89478 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-4&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-4)]} 0 0
[junit4] 2> 89478 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89478 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89478 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89478 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89479 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89479 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89479 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89479 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89480 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89480 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89480 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-6&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-6)]} 0 0
[junit4] 2> 89481 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89481 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89481 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-8&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-8)]} 0 0
[junit4] 2> 89481 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
BUFFERING replay: false
[junit4] 2> 89481 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89487 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:51&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89487 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:23&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89488 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0
[junit4] 2> 89488 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:70&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89488 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89488 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0
[junit4] 2> 89488 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=73&wt=json} status=0 QTime=0
[junit4] 2> 89488 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89488 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 QTime=0
[junit4] 2> 89489 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0
[junit4] 2> 89489 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89489 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 89489 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89489 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89489 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89489 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89489 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:141&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89490 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89490 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89490 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=99&wt=json} status=0 QTime=0
[junit4] 2> 89490 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 89490 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=97&wt=json} status=0 QTime=0
[junit4] 2> 89490 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=106&wt=json} status=0 QTime=0
[junit4] 2> 89491 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89653 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:131&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89653 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89653 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89654 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89655 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=2
[junit4] 2> 89655 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:134&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89659 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=67&wt=json} status=0 QTime=181
[junit4] 2> 89659 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
[junit4] 2> 89659 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
[junit4] 2> 89659 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=131&wt=json} status=0 QTime=0
[junit4] 2> 89660 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=107&wt=json} status=0 QTime=0
[junit4] 2> 89660 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:153&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89660 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89660 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=96&wt=json} status=0 QTime=0
[junit4] 2> 89660 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89660 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89661 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=48&wt=json} status=0 QTime=0
[junit4] 2> 89661 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89661 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=0
[junit4] 2> 89661 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89661 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
[junit4] 2> 89661 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 89661 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:50&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89662 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89662 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
[junit4] 2> 89662 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:101&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89662 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89662 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=149&wt=json} status=0 QTime=0
[junit4] 2> 89662 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89662 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=9
[junit4] 2> 89663 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=45&wt=json} status=0 QTime=0
[junit4] 2> 89663 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:30&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89663 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89663 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
[junit4] 2> 89663 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:131&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89663 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
[junit4] 2> 89663 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=157&wt=json} status=0 QTime=0
[junit4] 2> 89663 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89664 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:87&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89664 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:52&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89664 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
[junit4] 2> 89664 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89664 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
[junit4] 2> 89665 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89665 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:137&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89665 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89666 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:145&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89666 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89666 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:39&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89666 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89666 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
[junit4] 2> 89666 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=156&wt=json} status=0 QTime=0
[junit4] 2> 89667 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:106&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89667 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=137&wt=json} status=0 QTime=0
[junit4] 2> 89667 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
[junit4] 2> 89667 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=77&wt=json} status=0 QTime=0
[junit4] 2> 89667 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=131&wt=json} status=0 QTime=0
[junit4] 2> 89667 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89668 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=112&wt=json} status=0 QTime=0
[junit4] 2> 89668 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=45&wt=json} status=0 QTime=0
[junit4] 2> 89668 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89668 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89668 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=57&wt=json} status=0 QTime=0
[junit4] 2> 89668 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=73&wt=json} status=0 QTime=0
[junit4] 2> 89668 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
[junit4] 2> 89668 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 89669 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=111&wt=json} status=0 QTime=0
[junit4] 2> 89669 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=114&wt=json} status=0 QTime=0
[junit4] 2> 89669 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:93&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89669 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89669 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89669 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=146&wt=json} status=0 QTime=0
[junit4] 2> 89670 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89670 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:85&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89670 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:75&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89670 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=0
[junit4] 2> 89670 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:41&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89671 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:60&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89671 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0
[junit4] 2> 89671 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[41
(7)]} 0 190
[junit4] 2> 89671 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 89671 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
[junit4] 2> 89671 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 89671 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=112&wt=json} status=0 QTime=0
[junit4] 2> 89671 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=58&wt=json} status=0 QTime=0
[junit4] 2> 89672 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=31&wt=json} status=0 QTime=0
[junit4] 2> 89672 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:43&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89672 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
[junit4] 2> 89672 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=43&wt=json} status=0 QTime=0
[junit4] 2> 89672 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89673 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:16&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89673 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=138&wt=json} status=0 QTime=0
[junit4] 2> 89673 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=119&wt=json} status=0 QTime=0
[junit4] 2> 89673 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89673 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-9&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-9)]} 0 191
[junit4] 2> 89673 INFO (WRITER0) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89673 INFO (WRITER0) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 19
[junit4] 2> 89674 INFO (WRITER0) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89674 INFO (WRITER0) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89675 INFO (WRITER4) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89675 INFO (WRITER4) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89675 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-11&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-11)]} 0 0
[junit4] 2> 89675 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-10&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-10)]} 0 0
[junit4] 2> 89677 INFO (WRITER4) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89677 INFO (WRITER4) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89677 INFO (WRITER4) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89677 INFO (WRITER4) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89677 INFO (WRITER4) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-13&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-13)]} 0 0
[junit4] 2> 89678 INFO (WRITER4) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89678 INFO (WRITER4) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89683 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89683 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89687 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89687 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89688 WARN (recoveryExecutor-299-thread-1) [ ]
o.a.s.u.UpdateLog Starting log replay
tlog{file=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/tlog/tlog.0000000000000000004
refcount=2} active=true starting pos=0
[junit4] 2> 89699 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[154
(15)]} 0 0
[junit4] 2> 89700 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89700 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89700 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89700 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89699 INFO (WRITER0) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[154
(12)]} 0 0
[junit4] 2> 89692 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-5&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[104
(-5)]} 0 210
[junit4] 2> 89701 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89701 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89692 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-16&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
(-16)]} 0 4
[junit4] 2> 89702 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[3
(18)]} 0 0
[junit4] 2> 89702 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-20&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
(-20)]} 0 0
[junit4] 2> 89702 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89702 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89711 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-19&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[159
(-19)]} 0 8
[junit4] 2> 89712 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89712 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89712 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89712 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89713 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-22&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[104
(-22)]} 0 0
[junit4] 2> 89713 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-23&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[104
(-23)]} 0 0
[junit4] 2> 89713 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-24&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[56
(-24)]} 0 0
[junit4] 2> 89713 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[56
(25)]} 0 0
[junit4] 2> 89713 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-26&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[56
(-26)]} 0 0
[junit4] 2> 89714 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89714 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89714 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-27&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[58
(-27)]} 0 0
[junit4] 2> 89714 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89714 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89715 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89715 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89715 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-28&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[58
(-28)]} 0 0
[junit4] 2> 89716 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-29&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[73
(-29)]} 0 0
[junit4] 2> 89716 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89716 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89716 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-30&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[73
(-30)]} 0 0
[junit4] 2> 89716 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89716 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89717 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89717 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89717 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-31&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[73
(-31)]} 0 0
[junit4] 2> 89717 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-32&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[73
(-32)]} 0 0
[junit4] 2> 89718 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89718 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89718 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-33&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[73
(-33)]} 0 0
[junit4] 2> 89718 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-34&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[27
(-34)]} 0 0
[junit4] 2> 89719 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89719 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89719 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-21&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
(-21)]} 0 0
[junit4] 2> 89719 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89719 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89720 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-36&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
(-36)]} 0 0
[junit4] 2> 89720 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89720 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89720 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89721 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89721 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89721 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89721 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89721 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89722 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89722 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89722 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-37&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
(-37)]} 0 0
[junit4] 2> 89722 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89723 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89692 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=154&wt=json} status=0 QTime=36
[junit4] 2> 89692 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=4
[junit4] 2> 89692 INFO (WRITER4) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-14&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[154
(-14)]} 0 3
[junit4] 2> 89692 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=3
[junit4] 2> 89692 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=38
[junit4] 2> 89692 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=35&wt=json} status=0 QTime=37
[junit4] 2> 89730 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[27
(35)]} 0 0
[junit4] 2> 89729 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-17&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[17
(-17)]} 0 5
[junit4] 2> 89727 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89756 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 32
[junit4] 2> 89756 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 start
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 89765 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_4,generation=4}
[junit4] 2> 89765 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.c.SolrDeletionPolicy newest commit generation = 4
[junit4] 2> 89766 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.s.SolrIndexSearcher Opening [Searcher@3bf49144[collection1] main]
[junit4] 2> 89766 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 89767 INFO (searcherExecutor-297-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@3bf49144[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.1.0):C5/2:delGen=1)))}
[junit4] 2> 89768 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] {delete=[154 (-4), 154 (-6),
154 (-8), 154 (-9), 154 (-11), 154 (-10), 154 (-13), 154 (-14), 3 (-16), 104
(-5), ... (28 deletes)],add=[41 (7), 154 (12), 154 (15), 3 (18), 56 (25), 27
(35)]} 0 79
[junit4] 2> 89768 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.u.UpdateLog Re-computing max version from index after log re-play.
[junit4] 2> 89771 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0
QTime=3
[junit4] 2> 89779 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:137&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89780 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
[junit4] 2> 89780 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=128&wt=json} status=0 QTime=0
[junit4] 2> 89781 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version
buckets from index
[junit4] 2> 89783 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=102&wt=json} status=0 QTime=0
[junit4] 2> 89783 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.u.VersionInfo Found MAX value 35 from Terms for _version_ in index
[junit4] 2> 89790 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=49&wt=json} status=0 QTime=0
[junit4] 2> 89790 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89791 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=1 status=0
QTime=0
[junit4] 2> 89791 INFO (recoveryExecutor-299-thread-1) [ ]
o.a.s.u.UpdateLog Took 22.0ms to seed version buckets with highest version 37
[junit4] 2> 89791 WARN (recoveryExecutor-299-thread-1) [ ]
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=6
deletes=28 deleteByQuery=0 errors=0 positionOfStart=0}
[junit4] 2> 89791 INFO (WRITER4) [ ] o.a.s.u.DirectUpdateHandler2
start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
[junit4] 2> 89792 INFO (WRITER4) [ ] o.a.s.c.SolrCore
SolrIndexSearcher has not changed - not re-opening:
org.apache.solr.search.SolrIndexSearcher
[junit4] 2> 89792 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:154&omitHeader=true&wt=json} hits=1 status=0
QTime=1
[junit4] 2> 89792 INFO (WRITER4) [ ] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 89792 INFO (WRITER4) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 23
[junit4] 2> 89793 INFO (WRITER4) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-38&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[43
(-38)]} 0 0
[junit4] 2> 89801 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:109&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89801 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
[junit4] 2> 89803 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=50&wt=json} status=0 QTime=0
[junit4] 2> 89803 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE,
tlog=tlog{file=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/tlog/tlog.0000000000000000005
refcount=1}}
[junit4] 2> 89804 INFO (WRITER0) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89804 INFO (WRITER0) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89807 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
[junit4] 2> 89807 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-41&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[49
(-41)]} 0 0
[junit4] 2> 89807 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89807 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89807 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:43&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89808 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
[junit4] 2> 89808 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 89808 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89808 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89808 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=49&wt=json} status=0 QTime=0
[junit4] 2> 89808 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=52&wt=json} status=0 QTime=0
[junit4] 2> 89808 INFO (WRITER2) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89808 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89808 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:57&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89808 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
[junit4] 2> 89809 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=49&wt=json} status=0 QTime=0
[junit4] 2> 89809 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-39&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[27
(-39)]} 0 0
[junit4] 2> 89809 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
[junit4] 2> 89809 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-43&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[49
(-43)]} 0 0
[junit4] 2> 89809 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:27&omitHeader=true&wt=json} hits=1 status=0
QTime=0
[junit4] 2> 89809 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89809 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89809 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
[junit4] 2> 89809 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-44&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[97
(-44)]} 0 0
[junit4] 2> 89810 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-46&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[97
(-46)]} 0 0
[junit4] 2> 89810 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:79&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89810 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=97&wt=json} status=0 QTime=0
[junit4] 2> 89810 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-47&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[97
(-47)]} 0 0
[junit4] 2> 89810 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
[junit4] 2> 89810 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-48&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[134
(-48)]} 0 0
[junit4] 2> 89810 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[49
(45)]} 0 0
[junit4] 2> 89810 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:94&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89810 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=102&wt=json} status=0 QTime=0
[junit4] 2> 89810 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-50&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[142
(-50)]} 0 0
[junit4] 2> 89811 INFO (WRITER3) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89811 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89811 INFO (READER2) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:134&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89811 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[84
(51)]} 0 0
[junit4] 2> 89811 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:49&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89812 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=102&wt=json} status=0 QTime=0
[junit4] 2> 89812 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=127&wt=json} status=0 QTime=0
[junit4] 2> 89812 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:152&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89812 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={q=id:105&omitHeader=true&wt=json} hits=0 status=0
QTime=0
[junit4] 2> 89812 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
[junit4] 2> 89813 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=136&wt=json} status=0 QTime=0
[junit4] 2> 89813 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=106&wt=json} status=0 QTime=0
[junit4] 2> 89813 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
[junit4] 2> 89813 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=30&wt=json} status=0 QTime=0
[junit4] 2> 89813 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
[junit4] 2> 89813 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=123&wt=json} status=0 QTime=0
[junit4] 2> 89813 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
[junit4] 2> 89813 INFO (READER3) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=142&wt=json} status=0 QTime=0
[junit4] 2> 89813 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=81&wt=json} status=0 QTime=0
[junit4] 2> 89814 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0
[junit4] 2> 89814 INFO (READER1) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=93&wt=json} status=0 QTime=0
[junit4] 2> 89814 INFO (READER4) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=32&wt=json} status=0 QTime=0
[junit4] 2> 89814 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-49&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[137
(-49)]} 0 0
[junit4] 2> 89814 INFO (READER0) [ ] o.a.s.c.S.Request [collection1]
webapp=null path=null params={qt=/get&ids=115&wt=json} status=0 QTime=0
[junit4] 2> 89814 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89814 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89814 INFO (WRITER1) [ ]
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state:
APPLYING_BUFFERED replay: false
[junit4] 2> 89815 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 0
[junit4] 2> 89815 INFO (WRITER1) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[39
(53)]} 0 0
[junit4] 2> 89815 INFO (WRITER0) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={_version_=-40&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[43
(-40)]} 0 0
[junit4] 2> 89815 INFO (WRITER1
[...truncated too long message...]
(Uninverting(_e(6.1.0):C1) Uninverting(_w(6.1.0):C1) Uninverting(_1z(6.1.0):C1)
Uninverting(_25(6.1.0):C2/1:delGen=1) Uninverting(_26(6.1.0):C22/21:delGen=8)
Uninverting(_2j(6.1.0):C10/9:delGen=4) Uninverting(_2o(6.1.0):C1)
Uninverting(_2p(6.1.0):C1) Uninverting(_2s(6.1.0):C1)
Uninverting(_2t(6.1.0):C1) Uninverting(_2w(6.1.0):C3/2:delGen=2)
Uninverting(_2y(6.1.0):C21/18:delGen=5) Uninverting(_2z(6.1.0):C1)
Uninverting(_31(6.1.0):C4/3:delGen=2) Uninverting(_35(6.1.0):C2/1:delGen=1)
Uninverting(_37(6.1.0):C14/8:delGen=2) Uninverting(_38(6.1.0):C1)
Uninverting(_39(6.1.0):C8/6:delGen=1) Uninverting(_3a(6.1.0):C1)))}
[junit4] 2> 104225 INFO (WRITER2) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 81
[junit4] 2> 104225 INFO (WRITER3) [ ] o.a.s.u.DirectUpdateHandler2
start
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 104227 INFO (WRITER3) [ ] o.a.s.c.SolrDeletionPolicy
SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_4w,generation=176}
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@4486d6ec
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@54fb41f9),segFN=segments_4x,generation=177}
[junit4] 2> 104227 INFO (WRITER3) [ ] o.a.s.c.SolrDeletionPolicy
newest commit generation = 177
[junit4] 2> 104227 INFO (WRITER3) [ ] o.a.s.s.SolrIndexSearcher
Opening [Searcher@2cff2f00[collection1] main]
[junit4] 2> 104227 INFO (WRITER3) [ ] o.a.s.u.DirectUpdateHandler2
end_commit_flush
[junit4] 2> 104228 INFO (searcherExecutor-297-thread-1) [ ]
o.a.s.c.SolrCore [collection1] Registered new searcher
Searcher@2cff2f00[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_e(6.1.0):C1)
Uninverting(_w(6.1.0):C1) Uninverting(_1z(6.1.0):C1)
Uninverting(_25(6.1.0):C2/1:delGen=1) Uninverting(_26(6.1.0):C22/21:delGen=8)
Uninverting(_2j(6.1.0):C10/9:delGen=4) Uninverting(_2o(6.1.0):C1)
Uninverting(_2p(6.1.0):C1) Uninverting(_2s(6.1.0):C1)
Uninverting(_2t(6.1.0):C1) Uninverting(_2w(6.1.0):C3/2:delGen=2)
Uninverting(_2y(6.1.0):C21/18:delGen=5) Uninverting(_2z(6.1.0):C1)
Uninverting(_31(6.1.0):C4/3:delGen=2) Uninverting(_35(6.1.0):C2/1:delGen=1)
Uninverting(_37(6.1.0):C14/8:delGen=2) Uninverting(_38(6.1.0):C1)
Uninverting(_39(6.1.0):C8/6:delGen=1) Uninverting(_3a(6.1.0):C1)))}
[junit4] 2> 104229 INFO (WRITER3) [ ]
o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null
params={}{commit=} 0 3
[junit4] 2> 104230 WARN (recoveryExecutor-299-thread-6) [ ]
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=7
deletes=32 deleteByQuery=0 errors=0 positionOfStart=175}
[junit4] 2> 104230 INFO
(TEST-TestStressRecovery.testStressRecovery-seed#[9E66594D2DC14A4]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testStressRecovery
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestStressRecovery
-Dtests.method=testStressRecovery -Dtests.seed=9E66594D2DC14A4
-Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=sr-Latn-ME
-Dtests.timezone=Africa/Dar_es_Salaam -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 14.9s J2 | TestStressRecovery.testStressRecovery <<<
[junit4] > Throwable #1:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught
exception in thread: Thread[id=485, name=READER3, state=RUNNABLE,
group=TGRP-TestStressRecovery]
[junit4] > at
__randomizedtesting.SeedInfo.seed([9E66594D2DC14A4:B3DC0CC94D34ABAA]:0)
[junit4] > Caused by: java.lang.RuntimeException: java.lang.AssertionError
[junit4] > at
__randomizedtesting.SeedInfo.seed([9E66594D2DC14A4]:0)
[junit4] > at
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:331)
[junit4] > Caused by: java.lang.AssertionError
[junit4] > at
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:318)
[junit4] 2> 104234 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 104234 INFO
(SUITE-TestStressRecovery-seed#[9E66594D2DC14A4]-worker) [ ]
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1296582111
[junit4] 2> 104247 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore
org.apache.solr.core.SolrCore@64f32932
[junit4] 2> 104247 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.u.DirectUpdateHandler2 closing
DirectUpdateHandler2{commits=530,autocommits=0,soft
autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=1,cumulative_adds=304,cumulative_deletesById=1191,cumulative_deletesByQuery=1,cumulative_errors=1,transaction_logs_total_size=2866,transaction_logs_total_number=3}
[junit4] 2> 104247 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
[junit4] 2> 104247 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached
0 - closing IndexWriter
[junit4] 2> 104247 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with
IndexWriterCloser
[junit4] 2> 104255 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
[junit4] 2> 104293 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2
directories currently being tracked
[junit4] 2> 104293 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001;done=false>>]
[junit4] 2> 104293 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory,
CoreContainer#isShutdown=true
[junit4] 2> 104293 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001
[junit4] 2> 104293 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory looking to close
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/index
[CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/index;done=false>>]
[junit4] 2> 104293 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory,
CoreContainer#isShutdown=true
[junit4] 2> 104293 INFO (coreCloseExecutor-302-thread-1) [
x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_9E66594D2DC14A4-001/init-core-data-001/index
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60):
{val_l=PostingsFormat(name=Memory doPackFST= false),
_version_=PostingsFormat(name=Memory doPackFST= false), id=FST50},
docValues:{}, maxPointsInLeafNode=781, maxMBSortInHeap=4.027789095944839,
sim=ClassicSimilarity, locale=sr-Latn-ME, timezone=Africa/Dar_es_Salaam
[junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation
1.8.0_74 (64-bit)/cpus=4,threads=1,free=193491944,total=335020032
[junit4] 2> NOTE: All tests run in this JVM: [TestCloudDeleteByQuery,
TestComplexPhraseQParserPlugin, PrimitiveFieldTypeTest, TestFastWriter,
TestSuggestSpellingConverter, SmileWriterTest, TestFuzzyAnalyzedSuggestions,
FieldMutatingUpdateProcessorTest, TestSolrJ, BinaryUpdateRequestHandlerTest,
TestDefaultStatsCache, DebugComponentTest, DirectUpdateHandlerTest,
TestStressRecovery]
[junit4] Completed [36/585 (1!)] on J2 in 16.33s, 1 test, 1 error <<<
FAILURES!
[...truncated 1801 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:740: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:684: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/build.xml:59: The
following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build.xml:233:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/common-build.xml:529:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1457:
The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/lucene/common-build.xml:1014:
There were test failures: 585 suites (10 ignored), 2362 tests, 1 error, 85
ignored (70 assumptions) [seed: 9E66594D2DC14A4]
Total time: 87 minutes 10 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
