[JENKINS] Lucene-Solr-master-Linux (32bit/jdk1.8.0_162) - Build # 21994 - Still Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21994/
Java: 32bit/jdk1.8.0_162 -client -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.search.TestStressReorder.testStressReorderVersions

Error Message:
Captured an uncaught exception in thread: Thread[id=31078, name=WRITER9, 
state=RUNNABLE, group=TGRP-TestStressReorder]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=31078, name=WRITER9, state=RUNNABLE, 
group=TGRP-TestStressReorder]
        at 
__randomizedtesting.SeedInfo.seed([C2D69ECAC5E27E5:10EB50FAA695A368]:0)
Caused by: java.lang.RuntimeException: java.lang.NullPointerException
        at __randomizedtesting.SeedInfo.seed([C2D69ECAC5E27E5]:0)
        at 
org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:253)
Caused by: java.lang.NullPointerException
        at 
org.apache.solr.update.UpdateLog.getCurrentLogSizeFromStream(UpdateLog.java:299)
        at 
org.apache.solr.update.DirectUpdateHandler2.getCurrentTLogSize(DirectUpdateHandler2.java:1007)
        at 
org.apache.solr.update.DirectUpdateHandler2.updateDeleteTrackers(DirectUpdateHandler2.java:432)
        at 
org.apache.solr.update.DirectUpdateHandler2.delete(DirectUpdateHandler2.java:465)
        at 
org.apache.solr.update.processor.RunUpdateProcessor.processDelete(RunUpdateProcessorFactory.java:75)
        at 
org.apache.solr.update.processor.UpdateRequestProcessor.processDelete(UpdateRequestProcessor.java:59)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalDelete(DistributedUpdateProcessor.java:956)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionDelete(DistributedUpdateProcessor.java:1844)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doDeleteById(DistributedUpdateProcessor.java:1381)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processDelete(DistributedUpdateProcessor.java:1359)
        at 
org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processDelete(LogUpdateProcessorFactory.java:124)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleDeleteMap(JsonLoader.java:394)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleDeleteCommand(JsonLoader.java:311)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:171)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:121)
        at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:84)
        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2510)
        at 
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:125)
        at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1286)
        at 
org.apache.solr.SolrTestCaseJ4.deleteAndGetVersion(SolrTestCaseJ4.java:1464)
        at 
org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:184)




Build Log:
[...truncated 13677 lines...]
   [junit4] Suite: org.apache.solr.search.TestStressReorder
   [junit4]   2> 1552441 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-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/J2/temp/solr.search.TestStressReorder_C2D69ECAC5E27E5-001/init-core-data-001
   [junit4]   2> 1552442 WARN  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1011 numCloses=1011
   [junit4]   2> 1552442 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1552444 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1552446 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1552446 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-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> 1552480 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1552507 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1552591 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1552645 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11ffc77
   [junit4]   2> 1552652 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11ffc77
   [junit4]   2> 1552653 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@11ffc77
   [junit4]   2> 1552654 INFO  (coreLoadExecutor-6751-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1552654 INFO  (coreLoadExecutor-6751-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> 1552697 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1552711 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1552784 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1552795 INFO  (coreLoadExecutor-6751-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> 1552795 INFO  (coreLoadExecutor-6751-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@11ffc77
   [junit4]   2> 1552796 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1552796 INFO  (coreLoadExecutor-6751-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/J2/temp/solr.search.TestStressReorder_C2D69ECAC5E27E5-001/init-core-data-001/]
   [junit4]   2> 1552798 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=18, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=72.689453125, 
floorSegmentMB=1.9013671875, forceMergeDeletesPctAllowed=2.45416667519729, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.7523069537928725
   [junit4]   2> 1552802 WARN  (coreLoadExecutor-6751-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> 1552851 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1552851 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1552853 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1552853 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1552854 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=26.65234375, 
floorSegmentMB=0.8916015625, forceMergeDeletesPctAllowed=25.989780630057016, 
segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1552854 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@8ccf1b[collection1] 
main]
   [junit4]   2> 1552854 WARN  (coreLoadExecutor-6751-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> 1552855 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1552856 INFO  
(searcherExecutor-6752-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@8ccf1b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1552856 INFO  (coreLoadExecutor-6751-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1600212734961516544
   [junit4]   2> 1552858 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1552862 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] o.a.s.SolrTestCaseJ4 ###Starting testStressReorderVersions
   [junit4]   2> 1552862 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1552863 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@d7395c[collection1] realtime]
   [junit4]   2> 1552863 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] 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 0
   [junit4]   2> 1552863 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1600212734968856576,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1552863 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@68b7c4 
commitCommandVersion:1600212734968856576
   [junit4]   2> 1552864 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] o.a.s.s.SolrIndexSearcher Opening [Searcher@197f509[collection1] main]
   [junit4]   2> 1552864 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1552866 INFO  (searcherExecutor-6752-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@197f509[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1552866 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1552868 INFO  
(TEST-TestStressReorder.testStressReorderVersions-seed#[C2D69ECAC5E27E5]) [    
] o.a.s.s.TestStressReorder VERBOSE: commitPercent 8 softCommitPercent 68 
deletePercent 14 deleteByQueryPercent 6 ndocs 29 nWriteThreads 13 
percentRealtimeQuery 75 operations 50000 nReadThreads 17
   [junit4]   2> 1552870 INFO  (WRITER0) [    ] o.a.s.s.TestStressReorder 
VERBOSE: hardCommit start
   [junit4]   2> 1552870 INFO  (WRITER2) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 7 val= 2 version 2
   [junit4]   2> 1552871 INFO  (WRITER5) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 0 val= 2 version 4
   [junit4]   2> 1552871 INFO  (WRITER4) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 0 val= 2 version 3
   [junit4]   2> 1552871 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1600212734977245184,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1552871 INFO  (WRITER6) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleteByQuery id 28 val= 2 version 5
   [junit4]   2> 1552871 INFO  (WRITER3) [    ] o.a.s.s.TestStressReorder 
VERBOSE: hardCommit start
   [junit4]   2> 1552870 INFO  (WRITER1) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 0 val= 2 version 1
   [junit4]   2> 1552871 INFO  (WRITER7) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleteByQuery id 11 val= 2 version 6
   [junit4]   2> 1552871 INFO  (WRITER8) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 0 val= 2 version 7
   [junit4]   2> 1552872 INFO  (WRITER0) [    ] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@68b7c4 
commitCommandVersion:1600212734977245184
   [junit4]   2> 1552872 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-2&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[7
 (-2)]} 0 0
   [junit4]   2> 1552872 INFO  (WRITER9) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 18 val= 2 version 8
   [junit4]   2> 1552872 INFO  (WRITER2) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 7 val= 2 version 2 DONE
   [junit4]   2> 1552872 INFO  (WRITER2) [    ] o.a.s.s.TestStressReorder 
VERBOSE: softCommit start
   [junit4]   2> 1552872 INFO  (WRITER0) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@98c726[collection1] main]
   [junit4]   2> 1552872 INFO  (WRITER10) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 22 val= 2 version 9
   [junit4]   2> 1552872 INFO  (WRITER11) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 22 val= 2 version 10
   [junit4]   2> 1552872 INFO  (WRITER12) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 22 val= 2 version 11
   [junit4]   2> 1552872 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1552874 INFO  (searcherExecutor-6752-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@98c726[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1552874 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=1
   [junit4]   2> 1552874 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=1
   [junit4]   2> 1552875 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=1
   [junit4]   2> 1552875 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 
QTime=0
   [junit4]   2> 1552875 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:13&omitHeader=true&wt=json} 
hits=0 status=0 QTime=1
   [junit4]   2> 1552875 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
   [junit4]   2> 1552875 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=1
   [junit4]   2> 1552875 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=1
   [junit4]   2> 1552875 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=2
   [junit4]   2> 1552875 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=2
   [junit4]   2> 1552875 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1552875 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1552875 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1552875 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1552875 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552875 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> 1552875 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 
QTime=0
   [junit4]   2> 1552875 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> 1552875 INFO  (READER12) [    ] 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> 1552875 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=1
   [junit4]   2> 1552876 INFO  (READER8) [    ] 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> 1552876 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 1552876 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 
QTime=0
   [junit4]   2> 1552876 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=3
   [junit4]   2> 1552876 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=3
   [junit4]   2> 1552876 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:19&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 1552876 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> 1552876 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 
QTime=0
   [junit4]   2> 1552876 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> 1552876 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 
QTime=0
   [junit4]   2> 1552876 INFO  (WRITER6) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-5&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=id:28
 (-5)} 0 4
   [junit4]   2> 1552876 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 
QTime=0
   [junit4]   2> 1552876 INFO  (READER0) [    ] 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> 1552876 INFO  (WRITER5) [    ] o.a.s.u.DirectUpdateHandler2 
Reordered DBQs detected.  Update=add{_version_=4,id=0} 
DBQs=[DBQ{version=5,q=id:28}]
   [junit4]   2> 1552876 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1552876 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 
QTime=0
   [junit4]   2> 1552876 INFO  (WRITER3) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1600212734977245185,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1552877 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 
QTime=1
   [junit4]   2> 1552877 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 
QTime=0
   [junit4]   2> 1552877 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 
QTime=0
   [junit4]   2> 1552877 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> 1552876 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 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> 1552877 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 
QTime=0
   [junit4]   2> 1552877 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> 1552877 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 
QTime=0
   [junit4]   2> 1552877 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER4) [    ] 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> 1552877 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> 1552877 INFO  (READER13) [    ] 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> 1552877 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 
QTime=1
   [junit4]   2> 1552877 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=1
   [junit4]   2> 1552877 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 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> 1552877 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 
QTime=1
   [junit4]   2> 1552877 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=1
   [junit4]   2> 1552877 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 
QTime=0
   [junit4]   2> 1552877 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER10) [    ] 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> 1552877 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 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> 1552877 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER3) [    ] 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> 1552877 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 
QTime=0
   [junit4]   2> 1552877 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 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> 1552878 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> 1552878 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> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (WRITER0) [    ] o.a.s.s.TestStressReorder 
VERBOSE: hardCommit end
   [junit4]   2> 1552878 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> 1552878 INFO  (READER3) [    ] 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> 1552878 INFO  (WRITER6) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleteByQuery id 28 val= 2 version 5 DONE
   [junit4]   2> 1552878 INFO  (WRITER6) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 28 val= 3 version 13
   [junit4]   2> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (WRITER0) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 22 val= 2 version 12
   [junit4]   2> 1552878 INFO  (READER5) [    ] 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> 1552878 INFO  (READER3) [    ] 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> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552877 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=1
   [junit4]   2> 1552877 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> 1552877 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=1
   [junit4]   2> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 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> 1552878 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> 1552878 INFO  (READER9) [    ] 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> 1552878 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> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552878 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] 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> 1552878 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552878 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=2
   [junit4]   2> 1552879 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER3) [    ] 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> 1552879 INFO  (READER0) [    ] 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> 1552879 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER0) [    ] 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> 1552879 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 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> 1552879 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 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> 1552878 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 
QTime=0
   [junit4]   2> 1552877 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 
QTime=0
   [junit4]   2> 1552877 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 
QTime=1
   [junit4]   2> 1552879 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:28&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 1552879 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> 1552879 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:28&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 
QTime=2
   [junit4]   2> 1552879 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=2
   [junit4]   2> 1552879 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1552877 INFO  (READER15) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 
QTime=1
   [junit4]   2> 1552880 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:23&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER15) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 
QTime=1
   [junit4]   2> 1552878 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552878 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER2) [    ] 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> 1552878 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 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> 1552880 INFO  (READER14) [    ] 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> 1552880 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER2) [    ] 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> 1552880 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> 1552880 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:28&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:16&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:25&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 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> 1552880 INFO  (READER14) [    ] 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> 1552880 INFO  (READER15) [    ] 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> 1552880 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> 1552879 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:25&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER3) [    ] 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> 1552881 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 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> 1552881 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER7) [    ] 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> 1552881 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> 1552881 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552879 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=0
   [junit4]   2> 1552881 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:19&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552879 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 
QTime=0
   [junit4]   2> 1552881 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER10) [    ] 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> 1552881 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=0
   [junit4]   2> 1552881 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1552881 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=0
   [junit4]   2> 1552881 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=0
   [junit4]   2> 1552881 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 
QTime=0
   [junit4]   2> 1552881 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 
QTime=0
   [junit4]   2> 1552881 INFO  (READER4) [    ] 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> 1552880 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> 1552880 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552880 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=0
   [junit4]   2> 1552880 INFO  (READER1) [    ] 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> 1552880 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1552881 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=0
   [junit4]   2> 1552881 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552882 INFO  (READER13) [    ] 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> 1552885 INFO  (WRITER5) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@1dd873e[collection1] realtime]
   [junit4]   2> 1552885 INFO  (WRITER3) [    ] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@68b7c4 
commitCommandVersion:1600212734977245185
   [junit4]   2> 1552885 INFO  (WRITER5) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[0 
(4)]} 0 14
   [junit4]   2> 1552885 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=3
   [junit4]   2> 1552885 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 
QTime=3
   [junit4]   2> 1552885 INFO  (WRITER5) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 26 val= 2 version 14
   [junit4]   2> 1552885 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=3
   [junit4]   2> 1552885 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=3
   [junit4]   2> 1552886 INFO  (WRITER4) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[0 
(3)]} 0 14
   [junit4]   2> 1552885 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=3
   [junit4]   2> 1552886 INFO  (WRITER4) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 0 val= 3 version 15
   [junit4]   2> 1552886 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> 1552886 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> 1552886 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[0 
(1)]} 0 14
   [junit4]   2> 1552886 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=4
   [junit4]   2> 1552886 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552886 INFO  (WRITER3) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@a9a1a3[collection1] main]
   [junit4]   2> 1552886 INFO  (WRITER1) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleteByQuery id 0 val= 3 version 16
   [junit4]   2> 1552886 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=4
   [junit4]   2> 1552886 INFO  (READER15) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 
QTime=4
   [junit4]   2> 1552887 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 
QTime=4
   [junit4]   2> 1552887 INFO  (READER6) [    ] 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> 1552887 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=4
   [junit4]   2> 1552886 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1552886 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 
QTime=4
   [junit4]   2> 1552887 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> 1552887 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:19&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 1552887 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1552887 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> 1552887 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 1552886 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> 1552887 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=0
   [junit4]   2> 1552887 INFO  (READER1) [    ] 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> 1552887 INFO  (READER11) [    ] 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> 1552887 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552887 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 
QTime=5
   [junit4]   2> 1552887 INFO  (WRITER3) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1552889 INFO  (searcherExecutor-6752-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a9a1a3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c1)))}
   [junit4]   2> 1552889 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=3
   [junit4]   2> 1552889 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 
QTime=3
   [junit4]   2> 1552889 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=1
   [junit4]   2> 1552889 INFO  (WRITER3) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 17
   [junit4]   2> 1552889 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=3
   [junit4]   2> 1552889 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=2
   [junit4]   2> 1552889 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 
QTime=0
   [junit4]   2> 1552889 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1552890 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> 1552890 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=2
   [junit4]   2> 1552890 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=2
   [junit4]   2> 1552890 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=8
   [junit4]   2> 1552890 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=1
   [junit4]   2> 1552890 INFO  (READER9) [    ] 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> 1552890 INFO  (READER15) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=3
   [junit4]   2> 1552890 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 QTime=2
   [junit4]   2> 1552890 INFO  (READER6) [    ] 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> 1552890 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=8
   [junit4]   2> 1552890 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 
QTime=8
   [junit4]   2> 1552890 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=3
   [junit4]   2> 1552891 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:9&omitHeader=true&wt=json} 
hits=0 status=0 QTime=3
   [junit4]   2> 1552891 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 
QTime=9
   [junit4]   2> 1552891 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> 1552891 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 
QTime=0
   [junit4]   2> 1552891 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=0
   [junit4]   2> 1552891 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=1 status=0 
QTime=3
   [junit4]   2> 1552891 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> 1552891 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=0
   [junit4]   2> 1552891 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 QTime=0
   [junit4]   2> 1552891 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 
QTime=0
   [junit4]   2> 1552891 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 
QTime=0
   [junit4]   2> 1552891 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> 1552891 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 
QTime=0
   [junit4]   2> 1552891 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 
QTime=0
   [junit4]   2> 1552891 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1552891 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=3
   [junit4]   2> 1552891 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> 1552891 INFO  (WRITER10) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[22
 (-9)]} 0 18
   [junit4]   2> 1552891 INFO  (READER15) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 
QTime=0
   [junit4]   2> 1552891 INFO  (READER14) [    ] 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> 1552891 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> 1552891 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 
QTime=1
   [junit4]   2> 1552891 INFO  (READER6) [    ] 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> 1552892 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=2
   [junit4]   2> 1552891 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1600212734978293760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1552891 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> 1552892 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1552892 INFO  (READER8) [    ] 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> 1552892 INFO  (WRITER3) [    ] o.a.s.s.TestStressReorder 
VERBOSE: hardCommit end
   [junit4]   2> 1552892 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> 1552892 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1552891 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 1552892 INFO  (WRITER3) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 17 val= 2 version 17
   [junit4]   2> 1552892 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=1
   [junit4]   2> 1552892 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=1
   [junit4]   2> 1552892 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 
QTime=0
   [junit4]   2> 1552893 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=2
   [junit4]   2> 1552893 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 
QTime=2
   [junit4]   2> 1552892 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 
QTime=0
   [junit4]   2> 1552892 INFO  (WRITER7) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-6&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=id:11
 (-6)} 0 20
   [junit4]   2> 1552893 INFO  (WRITER6) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[28 
(13)]} 0 14
   [junit4]   2> 1552893 INFO  (READER0) [    ] 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> 1552893 INFO  (READER12) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 
QTime=0
   [junit4]   2> 1552893 INFO  (WRITER6) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 28 val= 4 version 18
   [junit4]   2> 1552891 INFO  (WRITER10) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleting id 22 val= 2 version 9 DONE
   [junit4]   2> 1552893 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:14&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 1552893 INFO  (WRITER10) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 7 val= 3 version 19
   [junit4]   2> 1552893 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={q=id:14&omitHeader=true&wt=json} 
hits=0 status=0 QTime=0
   [junit4]   2> 1552893 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 
QTime=0
   [junit4]   2> 1552893 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> 1552893 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 
QTime=0
   [junit4]   2> 1552891 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 
QTime=0
   [junit4]   2> 1552893 INFO  (WRITER11) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[22 
(10)]} 0 20
   [junit4]   2> 1552893 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 
QTime=0
   [junit4]   2> 1552893 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> 1552893 INFO  (READER16) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 
QTime=0
   [junit4]   2> 1552893 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552893 INFO  (WRITER11) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 5 val= 2 version 20
   [junit4]   2> 1552893 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1552893 INFO  (WRITER12) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[22 
(11)]} 0 21
   [junit4]   2> 1552893 INFO  (WRITER12) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 7 val= 3 version 21
   [junit4]   2> 1552891 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1552893 INFO  (READER16) [    ] 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> 1552893 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=15&wt=json} status=0 QTime=0
   [junit4]   2> 1552893 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> 1552893 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[22 
(12)]} 0 15
   [junit4]   2> 1552893 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1552893 INFO  (WRITER0) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 22 val= 3 version 22
   [junit4]   2> 1552894 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> 1552894 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> 1552894 INFO  (READER15) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 
QTime=0
   [junit4]   2> 1552894 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 1552894 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 1552894 INFO  (READER15) [    ] 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> 1552894 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552894 INFO  (READER15) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 
QTime=0
   [junit4]   2> 1552894 INFO  (READER8) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1552894 INFO  (READER15) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 
QTime=0
   [junit4]   2> 1552894 INFO  (READER8) [    ] 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> 1552893 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552893 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=28&wt=json} status=0 QTime=0
   [junit4]   2> 1552893 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 
QTime=0
   [junit4]   2> 1552894 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 
QTime=0
   [junit4]   2> 1552894 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 1552894 INFO  (READER14) [    ] 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> 1552894 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 
QTime=0
   [junit4]   2> 1552894 INFO  (READER3) [    ] 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> 1552894 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552894 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 
QTime=0
   [junit4]   2> 1552894 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 
QTime=0
   [junit4]   2> 1552894 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 
QTime=0
   [junit4]   2> 1552894 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> 1552894 INFO  (READER14) [    ] 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> 1552894 INFO  (READER11) [    ] 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> 1552895 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> 1552895 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=19&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 QTime=0
   [junit4]   2> 1552895 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552895 INFO  (READER14) [    ] 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> 1552895 INFO  (READER0) [    ] 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> 1552895 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 1552895 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1552895 INFO  (READER14) [    ] 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> 1552895 INFO  (READER0) [    ] 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> 1552895 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=22&wt=json} status=0 QTime=0
   [junit4]   2> 1552895 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 
QTime=0
   [junit4]   2> 1552893 INFO  (WRITER8) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[0 
(7)]} 0 21
   [junit4]   2> 1552893 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[18 
(8)]} 0 21
   [junit4]   2> 1552895 INFO  (READER0) [    ] 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> 1552895 INFO  (WRITER8) [    ] o.a.s.s.TestStressReorder 
VERBOSE: hardCommit start
   [junit4]   2> 1552896 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=18&wt=json} status=0 
QTime=0
   [junit4]   2> 1552896 INFO  (WRITER9) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 0 val= 3 version 23
   [junit4]   2> 1552896 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 
QTime=0
   [junit4]   2> 1552893 INFO  (WRITER5) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[26 
(14)]} 0 7
   [junit4]   2> 1552893 INFO  (WRITER7) [    ] o.a.s.s.TestStressReorder 
VERBOSE: deleteByQuery id 11 val= 2 version 6 DONE
   [junit4]   2> 1552896 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> 1552896 INFO  (WRITER7) [    ] o.a.s.s.TestStressReorder 
VERBOSE: softCommit start
   [junit4]   2> 1552893 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 
QTime=0
   [junit4]   2> 1552893 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> 1552893 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=2
   [junit4]   2> 1552896 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> 1552896 INFO  (READER13) [    ] 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> 1552896 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 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> 1552896 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=24&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 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> 1552896 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 INFO  (READER4) [    ] 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> 1552893 INFO  (READER9) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=21&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 INFO  (READER13) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=27&wt=json} status=0 
QTime=0
   [junit4]   2> 1552896 INFO  (READER10) [    ] 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> 1552896 INFO  (WRITER5) [    ] o.a.s.s.TestStressReorder 
VERBOSE: adding id 26 val= 3 version 24
   [junit4]   2> 1552896 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1552896 INFO  (READER14) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 
QTime=0
   [junit4]   2> 1552895 INFO  (READER11) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 
QTime=0
   [junit4]   2> 1552897 INFO  (READER13) [    ] 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> 1552897 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1552897 INFO  (READER2) [    ] 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> 1552897 INFO  (READER10) [    ] o.a.s.c.S.Request 
[collection1]  webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 
QTime=0
   [junit4]   2> 1552897 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> 1552897 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1552897 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=26&wt=json} status=0 QTime=0
   [junit4]   2> 1552897 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> 1552897 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1552897 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1552897 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> 1552897 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1552898 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null 

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

dUpdateProcessor.java:1844)
   [junit4]    >        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doDeleteById(DistributedUpdateProcessor.java:1381)
   [junit4]    >        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processDelete(DistributedUpdateProcessor.java:1359)
   [junit4]    >        at 
org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processDelete(LogUpdateProcessorFactory.java:124)
   [junit4]    >        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleDeleteMap(JsonLoader.java:394)
   [junit4]    >        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleDeleteCommand(JsonLoader.java:311)
   [junit4]    >        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:171)
   [junit4]    >        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:121)
   [junit4]    >        at 
org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:84)
   [junit4]    >        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
   [junit4]    >        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
   [junit4]    >        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
   [junit4]    >        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2510)
   [junit4]    >        at 
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:125)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1286)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.deleteAndGetVersion(SolrTestCaseJ4.java:1464)
   [junit4]    >        at 
org.apache.solr.search.TestStressReorder$1.run(TestStressReorder.java:184)
   [junit4]   2> 1553696 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1553696 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=20692181
   [junit4]   2> 1553696 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1553696 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2dc05d: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@39dc92
   [junit4]   2> 1553700 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1553700 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@14483f1: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@1f5f135
   [junit4]   2> 1553702 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1553703 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@170cc6b: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@f18570
   [junit4]   2> 1553703 INFO  (coreCloseExecutor-6757-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1549411
   [junit4]   2> 1553703 INFO  (coreCloseExecutor-6757-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1549411
   [junit4]   2> 1553703 INFO  (coreCloseExecutor-6757-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@ccb2fc: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@16d646f
   [junit4]   2> 1553711 INFO  (coreCloseExecutor-6757-thread-1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1553711 INFO  (coreCloseExecutor-6757-thread-1) [    
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@68b7c4 commitCommandVersion:0
   [junit4]   2> 1553714 INFO  
(SUITE-TestStressReorder-seed#[C2D69ECAC5E27E5]-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@b0f03f),
 locale=es-EC, timezone=Etc/GMT-2
   [junit4]   2> NOTE: Linux 4.13.0-39-generic i386/Oracle Corporation 
1.8.0_162 (32-bit)/cpus=8,threads=1,free=127998784,total=399462400
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestLeaderInitiatedRecoveryThread, TestPullReplicaErrorHandling, TestUpdate, 
ShardSplitTest, DirectoryFactoryTest, AnalyticsMergeStrategyTest, 
TestValueSourceCache, TestSolrConfigHandlerCloud, SpatialRPTFieldTypeTest, 
TestNumericRangeQuery64, UUIDUpdateProcessorFallbackTest, V2ApiIntegrationTest, 
HealthCheckHandlerTest, SpellPossibilityIteratorTest, SpellCheckComponentTest, 
DistributedQueryComponentOptimizationTest, NotRequiredUniqueKeyTest, 
TestLazyCores, TestBlobHandler, BlockDirectoryTest, FileBasedSpellCheckerTest, 
AtomicUpdatesTest, TestRawResponseWriter, LukeRequestHandlerTest, 
CoreAdminHandlerTest, AnalysisAfterCoreReloadTest, LIROnShardRestartTest, 
TestCopyFieldCollectionResource, SpatialHeatmapFacetsTest, 
TestGraphTermsQParserPlugin, TestNamedUpdateProcessors, 
TestUniqueKeyFieldResource, TestCodecSupport, HdfsNNFailoverTest, 
TestPointFields, TestFaceting, TestSegmentSorting, ExecutePlanActionTest, 
JSONWriterTest, ConvertedLegacyTest, TestSolrCoreParser, 
OutOfBoxZkACLAndCredentialsProvidersTest, TestAnalyzedSuggestions, 
LeaderElectionTest, TestRandomCollapseQParserPlugin, 
AsyncCallRequestStatusResponseTest, TestCustomDocTransformer, 
TestJmxIntegration, TestSha256AuthenticationProvider, 
TestInPlaceUpdatesStandalone, TestFieldTypeResource, TestShardHandlerFactory, 
CacheHeaderTest, URLClassifyProcessorTest, TestCloudPseudoReturnFields, 
TimeZoneUtilsTest, TestRecoveryHdfs, TestSuggestSpellingConverter, 
AddReplicaTest, OverseerTaskQueueTest, DirectSolrSpellCheckerTest, 
TestRestoreCore, ChangedSchemaMergeTest, DistanceFunctionTest, TestCustomSort, 
LeaderElectionIntegrationTest, IndexSchemaRuntimeFieldTest, TestCoreContainer, 
RequestLoggingTest, TestInitParams, MultiSolrCloudTestCaseTest, TestConfigsApi, 
CdcrReplicationDistributedZkTest, TestSolrCloudWithHadoopAuthPlugin, 
ResponseLogComponentTest, TestGraphMLResponseWriter, TestDynamicLoading, 
DocValuesMultiTest, MinimalSchemaTest, TestPolicyCloud, 
DistributedFacetPivotLargeTest, TestReplicaProperties, 
BlockJoinFacetDistribTest, TestManagedSynonymGraphFilterFactory, 
CircularListTest, TestTolerantSearch, TestFastWriter, 
DistributedFacetPivotSmallTest, TestSolrQueryResponse, RulesTest, 
TermVectorComponentTest, TriLevelCompositeIdRoutingTest, 
TestTolerantUpdateProcessorRandomCloud, NoCacheHeaderTest, SuggesterTSTTest, 
DisMaxRequestHandlerTest, TestSkipOverseerOperations, DateFieldTest, 
ScheduledMaintenanceTriggerTest, TestDistributedStatsComponentCardinality, 
ClusterStateUpdateTest, InfixSuggestersTest, WordBreakSolrSpellCheckerTest, 
TestCloudSchemaless, AutoAddReplicasPlanActionTest, TestScoreJoinQPNoScore, 
TestExactSharedStatsCache, SolrCmdDistributorTest, SolrCLIZkUtilsTest, 
SolrJmxReporterTest, TestStressLiveNodes, TestDFISimilarityFactory, 
TestSolrCloudWithSecureImpersonation, CollectionTooManyReplicasTest, 
UnloadDistributedZkTest, SolrGangliaReporterTest, TestHdfsBackupRestoreCore, 
TestCoreDiscovery, TestSubQueryTransformerCrossCore, SearchHandlerTest, 
TestUnInvertedFieldException, TestCollectionsAPIViaSolrCloudCluster, 
TestLegacyNumericRangeQueryBuilder, PreAnalyzedFieldManagedSchemaCloudTest, 
TestLFUCache, HdfsTlogReplayBufferedWhileIndexingTest, NodeLostTriggerTest, 
TestExclusionRuleCollectionAccess, TestFiltering, BasicDistributedZk2Test, 
OverseerTest, BasicZkTest, RecoveryZkTest, TestZkChroot, TestRandomDVFaceting, 
DistributedSpellCheckComponentTest, TestRealTimeGet, TestStressReorder]
   [junit4] Completed [387/806 (1!)] on J2 in 1.29s, 1 test, 1 error <<< 
FAILURES!

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

[repro] Revision: bad0e3f164872c9e96fa366ab2dc1409581d7a34

[repro] Ant options: "-Dargs=-client -XX:+UseConcMarkSweepGC"
[repro] Repro line:  ant test  -Dtestcase=TestStressReorder 
-Dtests.method=testStressReorderVersions -Dtests.seed=C2D69ECAC5E27E5 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es-EC 
-Dtests.timezone=Etc/GMT-2 -Dtests.asserts=true -Dtests.file.encoding=UTF-8

[repro] ant clean

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

[...truncated 3298 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestStressReorder" -Dtests.showOutput=onerror "-Dargs=-client 
-XX:+UseConcMarkSweepGC" -Dtests.seed=C2D69ECAC5E27E5 -Dtests.multiplier=3 
-Dtests.slow=true -Dtests.locale=es-EC -Dtests.timezone=Etc/GMT-2 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8

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

[...truncated 40 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org