Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/21824/
Java: 64bit/jdk-11-ea+5 -XX:-UseCompressedOops -XX:+UseG1GC

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=14661, name=READER10, state=RUNNABLE, 
group=TGRP-TestStressRecovery]
        at 
__randomizedtesting.SeedInfo.seed([8E127538FDEB198B:34281C656203A685]:0)
Caused by: java.lang.RuntimeException: java.lang.NullPointerException
        at __randomizedtesting.SeedInfo.seed([8E127538FDEB198B]:0)
        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:332)
Caused by: java.lang.NullPointerException
        at 
org.apache.solr.update.ChannelFastInputStream.readWrappedStream(TransactionLog.java:898)
        at 
org.apache.solr.common.util.FastInputStream.refill(FastInputStream.java:88)
        at 
org.apache.solr.common.util.FastInputStream.readByte(FastInputStream.java:206)
        at 
org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:255)
        at org.apache.solr.update.TransactionLog.lookup(TransactionLog.java:531)
        at org.apache.solr.update.UpdateLog.lookup(UpdateLog.java:979)
        at 
org.apache.solr.handler.component.RealTimeGetComponent.process(RealTimeGetComponent.java:236)
        at 
org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:295)
        at 
org.apache.solr.handler.RealTimeGetHandler.handleRequestBody(RealTimeGetHandler.java:46)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2508)
        at org.apache.solr.util.TestHarness.query(TestHarness.java:337)
        at org.apache.solr.util.TestHarness.query(TestHarness.java:319)
        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:307)




Build Log:
[...truncated 14192 lines...]
   [junit4] Suite: org.apache.solr.search.TestStressRecovery
   [junit4]   2> 1183616 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-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/J0/temp/solr.search.TestStressRecovery_8E127538FDEB198B-001/init-core-data-001
   [junit4]   2> 1183617 WARN  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
   [junit4]   2> 1183617 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1183618 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 1183618 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog
   [junit4]   2> 1183618 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1183618 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-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> 1183639 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1183649 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1183750 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1183772 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10210abb
   [junit4]   2> 1183777 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10210abb
   [junit4]   2> 1183777 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@10210abb
   [junit4]   2> 1183778 INFO  (coreLoadExecutor-4514-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1183779 INFO  (coreLoadExecutor-4514-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> 1183790 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1183796 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1183853 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1183859 INFO  (coreLoadExecutor-4514-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> 1183860 INFO  (coreLoadExecutor-4514-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@10210abb
   [junit4]   2> 1183860 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1183860 INFO  (coreLoadExecutor-4514-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/J0/temp/solr.search.TestStressRecovery_8E127538FDEB198B-001/init-core-data-001/]
   [junit4]   2> 1183861 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=36, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.6628420587415993]
   [junit4]   2> 1183862 WARN  (coreLoadExecutor-4514-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> 1183901 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 1183901 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1183907 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1183907 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1183907 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=10, maxMergedSegmentMB=69.375, 
floorSegmentMB=1.9345703125, forceMergeDeletesPctAllowed=6.288121761643781, 
segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1183907 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@583e6636[collection1] main]
   [junit4]   2> 1183908 WARN  (coreLoadExecutor-4514-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> 1183908 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1183908 INFO  (coreLoadExecutor-4514-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1597738951753334784
   [junit4]   2> 1183909 INFO  
(searcherExecutor-4515-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@583e6636[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1183910 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1183912 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testStressRecovery
   [junit4]   2> 1183912 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1183912 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@463d31dc[collection1] realtime]
   [junit4]   2> 1183912 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
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> 1183913 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1597738951758577664,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1183913 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2eca4cf7 
commitCommandVersion:1597738951758577664
   [junit4]   2> 1183913 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@423bfd10[collection1] main]
   [junit4]   2> 1183913 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1183914 INFO  (searcherExecutor-4515-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@423bfd10[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1183914 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 1
   [junit4]   2> 1183918 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951763820544,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1183918 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1183918 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1183919 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951764869120,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1183919 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1183919 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1183919 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1183919 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951764869121,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951765917696,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951765917697,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951765917698,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1183920 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1183925 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-1&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0
 (-1)]} 0 3
   [junit4]   2> 1183994 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-2&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0
 (-2)]} 0 69
   [junit4]   2> 1183995 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-3&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[122
 (-3)]} 0 62
   [junit4]   2> 1184004 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-4&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0
 (-4)]} 0 0
   [junit4]   2> 1184005 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-7&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[71
 (-7)]} 0 0
   [junit4]   2> 1184005 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951855046656,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1184005 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184005 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184037 INFO  (READER3) [    ] 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> 1184038 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-8&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[122
 (-8)]} 0 0
   [junit4]   2> 1184038 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[96
 (-9)]} 0 0
   [junit4]   2> 1184038 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:127&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=110&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=52&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-10&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[31
 (-10)]} 0 0
   [junit4]   2> 1184038 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:31&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184038 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=130&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-11&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[130
 (-11)]} 0 0
   [junit4]   2> 1184038 INFO  (WRITER7) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951889649664,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184038 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:84&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184038 INFO  (WRITER7) [    ] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2eca4cf7 
commitCommandVersion:1597738951889649664
   [junit4]   2> 1184038 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=130&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:130&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=112&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:130&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184038 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=130&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=105&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:132&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=106&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:77&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=36&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=130&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:64&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=130&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER0) [    ] 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> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=130&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@4b4c6908[collection1] main]
   [junit4]   2> 1184039 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=125&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:64&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184039 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> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=130&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:55&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=52&wt=json} status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184038 INFO  (WRITER6) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-6&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[28
 (-6)]} 0 0
   [junit4]   2> 1184038 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-5&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[16
 (-5)]} 0 0
   [junit4]   2> 1184039 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:130&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184039 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=78&wt=json} status=0 QTime=0
   [junit4]   2> 1184040 INFO  (searcherExecutor-4515-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4b4c6908[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=77&wt=json} status=0 QTime=1
   [junit4]   2> 1184041 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-12&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[119
 (-12)]} 0 1
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:119&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:54&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:67&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184041 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-13&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[100
 (-13)]} 0 0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:127&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:16&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184041 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-14&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[100
 (-14)]} 0 0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=73&wt=json} status=0 QTime=0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=102&wt=json} status=0 QTime=0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:114&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0
   [junit4]   2> 1184041 INFO  (WRITER7) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184041 INFO  (WRITER7) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 3
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:73&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=123&wt=json} status=0 QTime=0
   [junit4]   2> 1184041 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=46&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:33&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:87&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=91&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:31&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=43&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=78&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=87&wt=json} status=0 QTime=0
   [junit4]   2> 1184042 INFO  (WRITER7) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951893843968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184042 INFO  (WRITER7) [    ] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2eca4cf7 
commitCommandVersion:1597738951893843968
   [junit4]   2> 1184043 INFO  (WRITER7) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@7474deaf[collection1] main]
   [junit4]   2> 1184045 INFO  (searcherExecutor-4515-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7474deaf[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1184045 INFO  (WRITER7) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184045 INFO  (WRITER7) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1184045 INFO  (WRITER5) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951896989696,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184045 INFO  (WRITER5) [    ] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1184045 INFO  (WRITER7) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-15&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[100
 (-15)]} 0 0
   [junit4]   2> 1184046 INFO  (WRITER7) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-16&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[122
 (-16)]} 0 0
   [junit4]   2> 1184046 INFO  (WRITER5) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184046 INFO  (WRITER5) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 1
   [junit4]   2> 1184047 INFO  (WRITER10) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951899086848,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184047 INFO  (WRITER10) [    ] o.a.s.u.DirectUpdateHandler2 
No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1184047 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=115&wt=json} status=0 QTime=0
   [junit4]   2> 1184047 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> 1184047 INFO  (WRITER10) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184047 INFO  (WRITER10) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184048 INFO  (WRITER8) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951900135424,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1184048 INFO  (WRITER8) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184048 INFO  (WRITER8) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184049 INFO  (WRITER8) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-17&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[78
 (-17)]} 0 0
   [junit4]   2> 1184049 INFO  (WRITER8) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951901184000,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184049 INFO  (WRITER8) [    ] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2eca4cf7 
commitCommandVersion:1597738951901184000
   [junit4]   2> 1184050 INFO  (WRITER10) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-18&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[78
 (-18)]} 0 0
   [junit4]   2> 1184050 INFO  (WRITER8) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@74e596c0[collection1] main]
   [junit4]   2> 1184050 INFO  (WRITER8) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184052 INFO  (searcherExecutor-4515-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@74e596c0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1184052 INFO  (WRITER8) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1184052 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951904329728,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1184052 INFO  (WRITER8) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-19&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[78
 (-19)]} 0 0
   [junit4]   2> 1184052 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184052 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184052 INFO  (WRITER5) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-20&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[78
 (-20)]} 0 0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=5
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] 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> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:64&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:132&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 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> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=107&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 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> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:92&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=124&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951905378304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1184053 INFO  (WRITER9) [    ] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@2eca4cf7 
commitCommandVersion:1597738951905378304
   [junit4]   2> 1184053 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=124&wt=json} status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:122&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:40&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184054 INFO  (WRITER9) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@55c5ecb6[collection1] main]
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
   [junit4]   2> 1184054 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:83&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184054 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184055 INFO  (searcherExecutor-4515-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@55c5ecb6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1184055 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 1
   [junit4]   2> 1184055 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> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:51&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:42&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184056 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951908524032,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:74&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=112&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=75&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=55&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=71&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=17&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951908524033,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=29&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184056 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184056 INFO  (READER5) [    ] 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> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=39&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=16
   [junit4]   2> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:131&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 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> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951908524034,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=122&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=119&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=70&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=47&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] 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> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=77&wt=json} status=0 QTime=0
   [junit4]   2> 1184056 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:54&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184056 INFO  (READER5) [    ] 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> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:47&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184057 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=91&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=124&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 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> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=43&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] 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> 1184057 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 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> 1184057 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=16&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 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> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=45&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=105&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:114&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951909572608,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:115&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184057 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951910621184,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184058 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=92&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184058 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=128&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=92&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951910621185,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184058 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 No 
uncommitted changes. Skipping IW.commit.
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=97&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=72&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1184058 INFO  (WRITER9) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:73&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:36&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184058 INFO  (WRITER9) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1597738951910621186,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1184058 INFO  (READER0) [    ] 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> 1184058 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> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=54&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:65&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=31&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=129&wt=json} status=0 QTime=0
   [junit4]   2> 1184058 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=88&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 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> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=91&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=77&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=66&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:56&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=67&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=129&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:29&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=43&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184059 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> 1184059 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> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=119&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=91&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=65&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=68&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=57&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=126&wt=json} status=0 QTime=0
   [junit4]   2> 1184059 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:46&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=95&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=62&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184060 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> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=53&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:53&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=118&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:92&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=46&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:43&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=76&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=69&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 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> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=114&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=33&wt=json} status=0 QTime=0
   [junit4]   2> 1184060 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=18
   [junit4]   2> 1184060 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:129&omitHeader=true&wt=json} hits=0 
status=0 QTime=21
   [junit4]   2> 1184061 INFO  (WRITER5) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-21&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[90
 (-21)]} 0 6
   [junit4]   2> 1184068 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=7
   [junit4]   2> 1184068 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> 1184068 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=105&wt=json} status=0 QTime=0
   [junit4]   2> 1184068 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=64&wt=json} status=0 QTime=0
   [junit4]   2> 1184068 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184068 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=48&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=37&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] 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> 1184069 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> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 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> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=45&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=108&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=69&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=56&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 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> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=118&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=108&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=108&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:105&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 1184069 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=67&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:40&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=87&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=76&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=84&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=23&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:106&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=74&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=32&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=107&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 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> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=120&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=88&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:37&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:66&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=51&wt=json} status=0 QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:82&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184070 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=20&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:49&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=48&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:101&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=46&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:121&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=49&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=60&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 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> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:120&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=49&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=42&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=39&wt=json} status=0 QTime=0
   [junit4]   2> 1184071 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> 1184071 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> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=57&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=132&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=87&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=69&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=31&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 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> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=111&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=100&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=83&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=127&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=113&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=62&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:36&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=44&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 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> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=86&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=98&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:129&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:34&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=88&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=94&wt=json} status=0 QTime=0
   [junit4]   2> 1184072 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=58&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=85&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=82&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=25&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 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> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:124&omitHeader=true&wt=json} hits=0 
status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:90&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=34&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=48&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=63&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:61&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=30&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=59&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=50&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=90&wt=json} status=0 QTime=0
   [junit4]   2> 1184073 INFO  (READER1) [  

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

che.solr.common.SolrException: java.nio.channels.ClosedChannelException
   [junit4]   2>        at 
org.apache.solr.update.TransactionLog.lookup(TransactionLog.java:534)
   [junit4]   2>        at 
org.apache.solr.update.UpdateLog.lookup(UpdateLog.java:979)
   [junit4]   2>        at 
org.apache.solr.handler.component.RealTimeGetComponent.process(RealTimeGetComponent.java:236)
   [junit4]   2>        at 
org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:295)
   [junit4]   2>        at 
org.apache.solr.handler.RealTimeGetHandler.handleRequestBody(RealTimeGetHandler.java:46)
   [junit4]   2>        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
   [junit4]   2>        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2508)
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.query(TestHarness.java:337)
   [junit4]   2>        at 
org.apache.solr.util.TestHarness.query(TestHarness.java:319)
   [junit4]   2>        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:307)
   [junit4]   2> Caused by: java.nio.channels.ClosedChannelException
   [junit4]   2>        at 
java.base/sun.nio.ch.FileChannelImpl.ensureOpen(FileChannelImpl.java:158)
   [junit4]   2>        at 
java.base/sun.nio.ch.FileChannelImpl.read(FileChannelImpl.java:796)
   [junit4]   2>        at 
org.apache.solr.update.ChannelFastInputStream.readWrappedStream(TransactionLog.java:898)
   [junit4]   2>        at 
org.apache.solr.common.util.FastInputStream.refill(FastInputStream.java:88)
   [junit4]   2>        at 
org.apache.solr.common.util.FastInputStream.readByte(FastInputStream.java:206)
   [junit4]   2>        at 
org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:255)
   [junit4]   2>        at 
org.apache.solr.update.TransactionLog.lookup(TransactionLog.java:531)
   [junit4]   2>        ... 9 more
   [junit4]   2> 
   [junit4]   2> 77762 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[8E127538FDEB198B]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testStressRecovery
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestStressRecovery 
-Dtests.method=testStressRecovery -Dtests.seed=8E127538FDEB198B 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true 
-Dtests.locale=mas -Dtests.timezone=Pacific/Chatham -Dtests.asserts=true 
-Dtests.file.encoding=UTF-8
   [junit4] ERROR   69.8s J1 | TestStressRecovery.testStressRecovery <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=38, name=READER5, state=RUNNABLE, 
group=TGRP-TestStressRecovery]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([8E127538FDEB198B:34281C656203A685]:0)
   [junit4]    > Caused by: java.lang.RuntimeException: 
org.apache.solr.common.SolrException: java.nio.channels.ClosedChannelException
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([8E127538FDEB198B]:0)
   [junit4]    >        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:332)
   [junit4]    > Caused by: org.apache.solr.common.SolrException: 
java.nio.channels.ClosedChannelException
   [junit4]    >        at 
org.apache.solr.update.TransactionLog.lookup(TransactionLog.java:534)
   [junit4]    >        at 
org.apache.solr.update.UpdateLog.lookup(UpdateLog.java:979)
   [junit4]    >        at 
org.apache.solr.handler.component.RealTimeGetComponent.process(RealTimeGetComponent.java:236)
   [junit4]    >        at 
org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:295)
   [junit4]    >        at 
org.apache.solr.handler.RealTimeGetHandler.handleRequestBody(RealTimeGetHandler.java:46)
   [junit4]    >        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
   [junit4]    >        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2508)
   [junit4]    >        at 
org.apache.solr.util.TestHarness.query(TestHarness.java:337)
   [junit4]    >        at 
org.apache.solr.util.TestHarness.query(TestHarness.java:319)
   [junit4]    >        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:307)
   [junit4]    > Caused by: java.nio.channels.ClosedChannelException
   [junit4]    >        at 
java.base/sun.nio.ch.FileChannelImpl.ensureOpen(FileChannelImpl.java:158)
   [junit4]    >        at 
java.base/sun.nio.ch.FileChannelImpl.read(FileChannelImpl.java:796)
   [junit4]    >        at 
org.apache.solr.update.ChannelFastInputStream.readWrappedStream(TransactionLog.java:898)
   [junit4]    >        at 
org.apache.solr.common.util.FastInputStream.refill(FastInputStream.java:88)
   [junit4]    >        at 
org.apache.solr.common.util.FastInputStream.readByte(FastInputStream.java:206)
   [junit4]    >        at 
org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:255)
   [junit4]    >        at 
org.apache.solr.update.TransactionLog.lookup(TransactionLog.java:531)
   [junit4]    >        ... 9 more
   [junit4]   2> 77770 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 77771 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=312048509
   [junit4]   2> 77778 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 77779 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2031337f: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@380caae9
   [junit4]   2> 77785 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 77785 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@2f3da32: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@4f1c815e
   [junit4]   2> 77785 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 77785 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@4036e6cc: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@87deb45
   [junit4]   2> 77802 INFO  (coreCloseExecutor-12-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@69661ed
   [junit4]   2> 77803 INFO  (coreCloseExecutor-12-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=69661ed
   [junit4]   2> 77803 INFO  (coreCloseExecutor-12-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@65a44e8c: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@476a8342
   [junit4]   2> 77893 INFO  
(SUITE-TestStressRecovery-seed#[8E127538FDEB198B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@24b0c1f7),
 locale=mas, timezone=Pacific/Chatham
   [junit4]   2> NOTE: Linux 4.13.0-37-generic amd64/Oracle Corporation 11-ea 
(64-bit)/cpus=8,threads=1,free=264603344,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestStressRecovery]
   [junit4] Completed [1/5 (1!)] on J1 in 76.16s, 1 test, 1 error <<< FAILURES!

[...truncated 28 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1568: 
The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-master-Linux/lucene/common-build.xml:1092: 
There were test failures: 5 suites, 5 tests, 1 error [seed: 8E127538FDEB198B]

Total time: 2 minutes 37 seconds

[repro] Setting last failure code to 256

[repro] Failures:
[repro]   1/5 failed: org.apache.solr.search.TestStressRecovery
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting 
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting 
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all 
warnings.
<Git Blamer> GIT_COMMIT=dad2d1076db632535c33fa118eb851ad7d0e2537, 
workspace=/var/lib/jenkins/workspace/Lucene-Solr-master-Linux
[WARNINGS] Computing warning deltas based on reference build #21823
Recording test results
Setting 
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting 
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting 
ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to