You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/03/24 09:50:41 UTC

[JENKINS] Lucene-Solr-master-Linux (64bit/jdk-9.0.4) - Build # 21691 - Still Unstable!

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21691/
Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseG1GC

1 tests failed.
FAILED:  org.apache.solr.search.TestStressRecovery.testStressRecovery

Error Message:
Captured an uncaught exception in thread: Thread[id=244, name=READER7, state=RUNNABLE, group=TGRP-TestStressRecovery]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=244, name=READER7, state=RUNNABLE, group=TGRP-TestStressRecovery]
	at __randomizedtesting.SeedInfo.seed([480CD2B01ED69D4F:F236BBED813E2241]:0)
Caused by: java.lang.RuntimeException: java.lang.NullPointerException
	at __randomizedtesting.SeedInfo.seed([480CD2B01ED69D4F]:0)
	at org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:332)
Caused by: java.lang.NullPointerException
	at org.apache.solr.update.TransactionLog$FSReverseReader.<init>(TransactionLog.java:807)
	at org.apache.solr.update.TransactionLog.getReverseReader(TransactionLog.java:645)
	at org.apache.solr.update.UpdateLog$RecentUpdates.update(UpdateLog.java:1405)
	at org.apache.solr.update.UpdateLog$RecentUpdates.<init>(UpdateLog.java:1340)
	at org.apache.solr.update.UpdateLog.getRecentUpdates(UpdateLog.java:1516)
	at org.apache.solr.search.TestRTGBase.getLatestVersions(TestRTGBase.java:103)
	at org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:326)




Build Log:
[...truncated 1853 lines...]
   [junit4] JVM J0: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/build/core/test/temp/junit4-J0-20180324_083118_9708122967356957003722.sysout
   [junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
   [junit4] codec: CheapBastard, pf: LuceneFixedGap, dvf: Asserting
   [junit4] <<< JVM J0: EOF ----

[...truncated 10271 lines...]
   [junit4] Suite: org.apache.solr.search.TestStressRecovery
   [junit4]   2> 22264 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestStressRecovery_480CD2B01ED69D4F-001/init-core-data-001
   [junit4]   2> 22265 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 22266 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
   [junit4]   2> 22267 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog
   [junit4]   2> 22267 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 22268 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 22297 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 22351 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 22357 WARN  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 22359 WARN  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieFloatField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 22360 WARN  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 22364 WARN  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.CurrencyField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 22373 WARN  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDateField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 22456 WARN  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 22459 WARN  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
   [junit4]   2> 22473 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 22546 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16151592
   [junit4]   2> 22546 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16151592
   [junit4]   2> 22546 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16151592
   [junit4]   2> 22550 INFO  (coreLoadExecutor-18-thread-1) [    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 22551 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 22567 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 22582 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 22655 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 22677 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 22678 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@16151592
   [junit4]   2> 22678 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 22678 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestStressRecovery_480CD2B01ED69D4F-001/init-core-data-001/]
   [junit4]   2> 22682 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=69.572265625, floorSegmentMB=1.48828125, forceMergeDeletesPctAllowed=6.326865520847665, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 22705 WARN  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
   [junit4]   2> 22755 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 22756 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 22762 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 22762 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 22763 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=99.58203125, floorSegmentMB=0.306640625, forceMergeDeletesPctAllowed=4.848226399052551, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.810992598668766
   [junit4]   2> 22764 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3327dfd5[collection1] main]
   [junit4]   2> 22765 WARN  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 22767 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 22770 INFO  (searcherExecutor-19-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3327dfd5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 22771 INFO  (coreLoadExecutor-18-thread-1) [    x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1595808735956041728
   [junit4]   2> 22776 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 22778 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testStressRecovery
   [junit4]   2> 22779 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 22780 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@22c74735[collection1] realtime]
   [junit4]   2> 22780 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] 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 1
   [junit4]   2> 22780 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595808735965478912,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 22780 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7b2f6757 commitCommandVersion:1595808735965478912
   [junit4]   2> 22782 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@56fbb087[collection1] main]
   [junit4]   2> 22782 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 22791 INFO  (searcherExecutor-19-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@56fbb087[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 22791 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 22800 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0
   [junit4]   2> 22801 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=59&wt=json} status=0 QTime=2
   [junit4]   2> 22801 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22801 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=2
   [junit4]   2> 22802 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:34&omitHeader=true&wt=json} hits=0 status=0 QTime=3
   [junit4]   2> 22802 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22803 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22803 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22803 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22805 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=1
   [junit4]   2> 22805 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22810 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=8
   [junit4]   2> 22810 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=69&wt=json} status=0 QTime=8
   [junit4]   2> 22810 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=35&wt=json} status=0 QTime=0
   [junit4]   2> 22810 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=78&wt=json} status=0 QTime=0
   [junit4]   2> 22810 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22810 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:76&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22810 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=4
   [junit4]   2> 22810 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=9
   [junit4]   2> 22810 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22810 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=72&wt=json} status=0 QTime=0
   [junit4]   2> 22810 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=33&wt=json} status=0 QTime=0
   [junit4]   2> 22810 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> 22810 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=32&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER5) [    ] 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> 22811 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=10
   [junit4]   2> 22811 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:67&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=103&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=50&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=80&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=33&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:86&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22811 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> 22811 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:63&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:96&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=50&wt=json} status=0 QTime=0
   [junit4]   2> 22811 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22812 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> 22812 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22812 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 22812 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22812 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=96&wt=json} status=0 QTime=0
   [junit4]   2> 22812 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22812 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> 22812 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=33&wt=json} status=0 QTime=0
   [junit4]   2> 22812 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> 22812 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22812 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 22812 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:18&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22812 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-2&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0 (-2)]} 0 13
   [junit4]   2> 22812 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=0
   [junit4]   2> 22812 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 22812 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=11
   [junit4]   2> 22812 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=9
   [junit4]   2> 22812 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> 22812 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=11
   [junit4]   2> 22813 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=76&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:88&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=61&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22810 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=8
   [junit4]   2> 22810 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-1&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0 (-1)]} 0 10
   [junit4]   2> 22813 INFO  (READER1) [    ] 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> 22813 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=67&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22813 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> 22813 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=59&wt=json} status=0 QTime=0
   [junit4]   2> 22812 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22814 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> 22814 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=78&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=53&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=76&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=39&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=49&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=102&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0
   [junit4]   2> 22814 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> 22811 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER0) [    ] 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> 22811 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 22811 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> 22814 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=30&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:32&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
   [junit4]   2> 22815 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> 22815 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=87&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:22&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22815 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> 22815 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:102&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:22&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22815 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> 22815 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22815 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=80&wt=json} status=0 QTime=0
   [junit4]   2> 22815 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> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=67&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:58&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER5) [    ] 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> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=59&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=57&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:86&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER3) [    ] 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> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=104&wt=json} status=0 QTime=0
   [junit4]   2> 22816 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> 22816 INFO  (READER5) [    ] 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> 22816 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> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 22816 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:68&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=76&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] 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> 22817 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:81&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] 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> 22817 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 22817 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> 22817 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=103&wt=json} status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22817 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22818 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> 22818 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=101&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=77&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:104&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=76&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:5&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:55&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22810 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.search.TestStressRecovery_480CD2B01ED69D4F-001/init-core-data-001/tlog/tlog.0000000000000000001.2 refcount=4}}
   [junit4]   2> 22816 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=69&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=88&wt=json} status=0 QTime=0
   [junit4]   2> 22818 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> 22818 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> 22818 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 22818 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=35&wt=json} status=0 QTime=0
   [junit4]   2> 22819 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 22819 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=68&wt=json} status=0 QTime=0
   [junit4]   2> 22819 INFO  (WRITER0) [    ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay: false
   [junit4]   2> 22819 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22819 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 0
   [junit4]   2> 22819 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:98&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22819 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22819 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=41&wt=json} status=0 QTime=0
   [junit4]   2> 22819 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22819 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22819 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> 22819 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> 22819 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> 22819 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
   [junit4]   2> 22819 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=94&wt=json} status=0 QTime=0
   [junit4]   2> 22819 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 22819 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=89&wt=json} status=0 QTime=0
   [junit4]   2> 22819 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> 22819 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> 22819 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22820 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:67&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22820 INFO  (READER0) [    ] 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> 22820 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> 22820 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:24&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22820 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> 22820 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22820 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=41&wt=json} status=0 QTime=0
   [junit4]   2> 22820 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22820 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=87&wt=json} status=0 QTime=0
   [junit4]   2> 22820 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> 22820 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:97&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22820 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> 22820 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22820 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> 22820 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22814 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> 22814 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=80&wt=json} status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:33&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=103&wt=json} status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:89&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=97&wt=json} status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=39&wt=json} status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:96&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=46&wt=json} status=0 QTime=0
   [junit4]   2> 22814 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22813 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 22813 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> 22822 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:27&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:88&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=81&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=73&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER7) [    ] 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> 22822 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:26&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER7) [    ] 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> 22822 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER2) [    ] 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> 22822 INFO  (READER8) [    ] 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> 22823 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=48&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=99&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER8) [    ] 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> 22823 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=81&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:80&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22821 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:27&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:83&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22823 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> 22823 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER6) [    ] 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> 22823 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER0) [    ] 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> 22823 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=78&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=68&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=52&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:100&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:74&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] 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> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:24&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=61&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=64&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER8) [    ] 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> 22825 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22821 INFO  (WRITER1) [    ] o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: BUFFERING replay: false
   [junit4]   2> 22821 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=[38 (-3)]} 0 0
   [junit4]   2> 22825 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 4
   [junit4]   2> 22821 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER8) [    ] 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> 22825 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=49&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=41&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=52&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=77&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=35&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=32&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER3) [    ] 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> 22826 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=0
   [junit4]   2> 22826 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=[38 (-4)]} 0 0
   [junit4]   2> 22826 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:38&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22826 INFO  (READER8) [    ] 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> 22818 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22826 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-5&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[38 (-5)]} 0 0
   [junit4]   2> 22826 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 22825 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 22827 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> 22827 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=61&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:103&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=72&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22824 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:100&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:69&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:27&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22827 INFO  (WRITER1) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={_version_=-6&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[44 (-6)]} 0 0
   [junit4]   2> 22827 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER4) [    ] 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> 22827 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER5) [    ] 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> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:92&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] 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> 22828 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:92&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:81&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] 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> 22828 INFO  (READER5) [    ] 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> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:97&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
   [junit4]   2> 22828 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=67&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:97&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:33&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22823 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:66&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22822 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=38&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=99&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0
   [junit4]   2> 22827 INFO  (READER3) [    ] 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> 22829 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=30&wt=json} status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER5) [    ] 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> 22829 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> 22829 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:76&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22829 INFO  (READER4) [    ] 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> 22830 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:63&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:81&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER6) [    ] 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> 22830 INFO  (READER7) [    ] 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> 22830 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=30&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:42&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER3) [    ] 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> 22830 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=73&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:76&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:21&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER3) [    ] 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> 22830 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:104&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=58&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=57&wt=json} status=0 QTime=0
   [junit4]   2> 22830 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=96&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=59&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 22831 INFO

[...truncated too long message...]

e=[4 (-1696)]} 0 5
   [junit4]   2> 29107 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1595808742594576384,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 29107 INFO  (WRITER0) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@7b2f6757 commitCommandVersion:1595808742594576384
   [junit4]   2> 29102 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=93&wt=json} status=0 QTime=0
   [junit4]   2> 29102 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 29105 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 29103 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 29103 INFO  (READER6) [    ] 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> 29103 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=64&wt=json} status=0 QTime=0
   [junit4]   2> 29110 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@64580d6e[collection1] main]
   [junit4]   2> 29103 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 29103 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=/get&ids=71&wt=json} status=0 QTime=0
   [junit4]   2> 29102 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 QTime=0
   [junit4]   2> 29115 INFO  (searcherExecutor-19-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64580d6e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 29115 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 29115 INFO  (WRITER0) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 12
   [junit4]   2> ŋ3 24, 2018 11:56:51 SÁRÚWÁ com.carrotsearch.randomizedtesting.RandomizedRunner$QueueUncaughtExceptionsHandler uncaughtException
   [junit4]   2> WARNING: Uncaught exception in thread: Thread[READER7,5,TGRP-TestStressRecovery]
   [junit4]   2> java.lang.RuntimeException: java.lang.NullPointerException
   [junit4]   2> 	at __randomizedtesting.SeedInfo.seed([480CD2B01ED69D4F]:0)
   [junit4]   2> 	at org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:332)
   [junit4]   2> Caused by: java.lang.NullPointerException
   [junit4]   2> 	at org.apache.solr.update.TransactionLog$FSReverseReader.<init>(TransactionLog.java:807)
   [junit4]   2> 	at org.apache.solr.update.TransactionLog.getReverseReader(TransactionLog.java:645)
   [junit4]   2> 	at org.apache.solr.update.UpdateLog$RecentUpdates.update(UpdateLog.java:1405)
   [junit4]   2> 	at org.apache.solr.update.UpdateLog$RecentUpdates.<init>(UpdateLog.java:1340)
   [junit4]   2> 	at org.apache.solr.update.UpdateLog.getRecentUpdates(UpdateLog.java:1516)
   [junit4]   2> 	at org.apache.solr.search.TestRTGBase.getLatestVersions(TestRTGBase.java:103)
   [junit4]   2> 	at org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:326)
   [junit4]   2> 
   [junit4]   2> 29118 INFO  (TEST-TestStressRecovery.testStressRecovery-seed#[480CD2B01ED69D4F]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStressRecovery
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestStressRecovery -Dtests.method=testStressRecovery -Dtests.seed=480CD2B01ED69D4F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ksf -Dtests.timezone=Europe/Simferopol -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   6.34s J1 | TestStressRecovery.testStressRecovery <<<
   [junit4]    > Throwable #1: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=244, name=READER7, state=RUNNABLE, group=TGRP-TestStressRecovery]
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([480CD2B01ED69D4F:F236BBED813E2241]:0)
   [junit4]    > Caused by: java.lang.RuntimeException: java.lang.NullPointerException
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([480CD2B01ED69D4F]:0)
   [junit4]    > 	at org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:332)
   [junit4]    > Caused by: java.lang.NullPointerException
   [junit4]    > 	at org.apache.solr.update.TransactionLog$FSReverseReader.<init>(TransactionLog.java:807)
   [junit4]    > 	at org.apache.solr.update.TransactionLog.getReverseReader(TransactionLog.java:645)
   [junit4]    > 	at org.apache.solr.update.UpdateLog$RecentUpdates.update(UpdateLog.java:1405)
   [junit4]    > 	at org.apache.solr.update.UpdateLog$RecentUpdates.<init>(UpdateLog.java:1340)
   [junit4]    > 	at org.apache.solr.update.UpdateLog.getRecentUpdates(UpdateLog.java:1516)
   [junit4]    > 	at org.apache.solr.search.TestRTGBase.getLatestVersions(TestRTGBase.java:103)
   [junit4]    > 	at org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:326)
   [junit4]   2> 29120 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 29120 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1547388301
   [junit4]   2> 29120 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 29121 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@171354ab: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@493c61f5
   [junit4]   2> 29125 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 29125 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@77e072ac: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@3f89e4df
   [junit4]   2> 29125 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 29125 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6a45d988: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@c3d1d6c
   [junit4]   2> 29126 INFO  (coreCloseExecutor-24-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@5c980f88
   [junit4]   2> 29126 INFO  (coreCloseExecutor-24-thread-1) [    x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=1553469320
   [junit4]   2> 29126 INFO  (coreCloseExecutor-24-thread-1) [    x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@42b4db21: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@14e10765
   [junit4]   2> 29135 INFO  (SUITE-TestStressRecovery-seed#[480CD2B01ED69D4F]-worker) [    ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@7cd76edf), locale=ksf, timezone=Europe/Simferopol
   [junit4]   2> NOTE: Linux 4.13.0-36-generic amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=8,threads=1,free=328186000,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestSolr4Spatial, HdfsLockFactoryTest, TestStressRecovery]
   [junit4] Completed [8/785 (1!)] on J1 in 6.89s, 1 test, 1 error <<< FAILURES!

[...truncated 45397 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21691/consoleText

[repro] Revision: ea504091e517b5a2941b76adef7270b3d6ecb34c

[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] Repro line:  ant test  -Dtestcase=TestStressRecovery -Dtests.method=testStressRecovery -Dtests.seed=480CD2B01ED69D4F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ksf -Dtests.timezone=Europe/Simferopol -Dtests.asserts=true -Dtests.file.encoding=UTF-8

[repro] ant clean

[...truncated 6 lines...]
[repro] Test suites by module:
[repro]    solr/core
[repro]       TestStressRecovery
[repro] ant compile-test

[...truncated 3301 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestStressRecovery" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=480CD2B01ED69D4F -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ksf -Dtests.timezone=Europe/Simferopol -Dtests.asserts=true -Dtests.file.encoding=UTF-8

[...truncated 76 lines...]
[repro] Failures:
[repro]   0/5 failed: org.apache.solr.search.TestStressRecovery
[repro] Exiting with code 0

[...truncated 40 lines...]