Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1863/
Java: 32bit/jdk1.8.0_162 -client -XX:+UseParallelGC

1 tests failed.
FAILED:  org.apache.solr.search.TestRealTimeGet.testStressGetRealtime

Error Message:
Captured an uncaught exception in thread: Thread[id=11116, name=WRITER8, 
state=RUNNABLE, group=TGRP-TestRealTimeGet]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=11116, name=WRITER8, state=RUNNABLE, 
group=TGRP-TestRealTimeGet]
        at 
__randomizedtesting.SeedInfo.seed([DA5670AD607B5B92:40587ACF46083830]:0)
Caused by: java.lang.RuntimeException: org.apache.solr.common.SolrException: 
Exception writing document id 15 to the index; possible analysis error.
        at __randomizedtesting.SeedInfo.seed([DA5670AD607B5B92]:0)
        at 
org.apache.solr.search.TestRealTimeGet$1.run(TestRealTimeGet.java:706)
Caused by: org.apache.solr.common.SolrException: Exception writing document id 
15 to the index; possible analysis error.
        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:246)
        at 
org.apache.solr.update.processor.RunUpdateProcessor.processAdd(RunUpdateProcessorFactory.java:67)
        at 
org.apache.solr.update.processor.UpdateRequestProcessor.processAdd(UpdateRequestProcessor.java:55)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.doLocalAdd(DistributedUpdateProcessor.java:950)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.versionAdd(DistributedUpdateProcessor.java:1163)
        at 
org.apache.solr.update.processor.DistributedUpdateProcessor.processAdd(DistributedUpdateProcessor.java:633)
        at 
org.apache.solr.update.processor.LogUpdateProcessorFactory$LogUpdateProcessor.processAdd(LogUpdateProcessorFactory.java:103)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.handleAdds(JsonLoader.java:501)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.processUpdate(JsonLoader.java:145)
        at 
org.apache.solr.handler.loader.JsonLoader$SingleThreadedJsonLoader.load(JsonLoader.java:121)
        at org.apache.solr.handler.loader.JsonLoader.load(JsonLoader.java:84)
        at 
org.apache.solr.handler.UpdateRequestHandler$1.load(UpdateRequestHandler.java:97)
        at 
org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:68)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2510)
        at 
org.apache.solr.servlet.DirectSolrConnection.request(DirectSolrConnection.java:125)
        at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1286)
        at 
org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1451)
        at 
org.apache.solr.search.TestRealTimeGet$1.run(TestRealTimeGet.java:675)
Caused by: java.lang.NullPointerException
        at 
org.apache.solr.update.UpdateLog.getCurrentLogSizeFromStream(UpdateLog.java:299)
        at 
org.apache.solr.update.DirectUpdateHandler2.getCurrentTLogSize(DirectUpdateHandler2.java:1007)
        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:291)
        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:234)
        ... 18 more




Build Log:
[...truncated 13766 lines...]
   [junit4] Suite: org.apache.solr.search.TestRealTimeGet
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestRealTimeGet_DA5670AD607B5B92-001/init-core-data-001
   [junit4]   2> 1131492 WARN  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=18 numCloses=18
   [junit4]   2> 1131492 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1131493 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 1131493 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1131493 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.SolrTestCaseJ4 updateLog impl=solr.CdcrUpdateLog
   [junit4]   2> 1131494 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1131494 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1131519 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
   [junit4]   2> 1131532 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=example
   [junit4]   2> 1131587 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 1131631 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15a5ed1
   [junit4]   2> 1131650 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15a5ed1
   [junit4]   2> 1131650 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15a5ed1
   [junit4]   2> 1131651 INFO  (coreLoadExecutor-4271-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1131652 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1131682 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.4.0
   [junit4]   2> 1131697 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 1131756 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid 
field id
   [junit4]   2> 1131764 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from instancedir 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1131764 INFO  (coreLoadExecutor-4271-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@15a5ed1
   [junit4]   2> 1131764 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1131764 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.search.TestRealTimeGet_DA5670AD607B5B92-001/init-core-data-001/]
   [junit4]   2> 1131766 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1131769 WARN  (coreLoadExecutor-4271-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> 1131807 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.CdcrUpdateLog
   [junit4]   2> 1131807 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1131809 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1131809 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1131810 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: 
minMergeSize=0, mergeFactor=10, maxMergeSize=295369721, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.1]
   [junit4]   2> 1131810 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@a340c9[collection1] 
main]
   [junit4]   2> 1131810 WARN  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/conf;
 switching to use InMemory storage instead.
   [junit4]   2> 1131811 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1131812 INFO  
(searcherExecutor-4272-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@a340c9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1131812 INFO  (coreLoadExecutor-4271-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1599816132573790208
   [junit4]   2> 1131815 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1131817 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testOptimisticLocking
   [junit4]   2> 1131818 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1131818 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1b28d63[collection1] realtime]
   [junit4]   2> 1131818 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 1
   [junit4]   2> 1131819 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132581130240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131819 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132581130240
   [junit4]   2> 1131819 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@126cb9e[collection1] main]
   [junit4]   2> 1131820 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131821 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@126cb9e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1131821 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1131824 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1599816132585324544)]} 0 1
   [junit4]   2> 1131825 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131825 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132585324543&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131825 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-1599816132585324544&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131825 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132585324614&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131825 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132585324543&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131825 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-1599816132585324544&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131826 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132585325242&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131826 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132585324544&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131826 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-1&versions=true&wt=json&indent=true}{delete=[I_do_not_exist 
(-1599816132588470272)]} 0 0
   [junit4]   2> 1131826 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1599816132588470273)]} 0 0
   [junit4]   2> 1131826 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132585324544&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131826 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132585324544&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131827 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132588470273&versions=true&wt=json&indent=true}{delete=[1
 (-1599816132589518848)]} 0 0
   [junit4]   2> 1131827 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132588470273&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131827 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1599816132588470273&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131827 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-1599816132588470272&versions=true&wt=json&indent=true}{add=[1
 (1599816132589518849)]} 0 0
   [junit4]   2> 1131827 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131828 INFO  
(TEST-TestRealTimeGet.testOptimisticLocking-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testOptimisticLocking
   [junit4]   2> 1131830 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testVersions
   [junit4]   2> 1131830 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1131830 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@16e55b5[collection1] realtime]
   [junit4]   2> 1131831 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
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> 1131831 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132593713152,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131831 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132593713152
   [junit4]   2> 1131831 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7799b9[collection1] main]
   [junit4]   2> 1131832 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131833 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7799b9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1131834 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1131835 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[1 (1599816132596858880)]} 0 1
   [junit4]   2> 1131836 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 1131836 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131836 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132598956032,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131836 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132598956032
   [junit4]   2> 1131839 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@14f9d41[collection1] main]
   [junit4]   2> 1131839 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131841 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@14f9d41[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.4.0):c1)))}
   [junit4]   2> 1131841 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 1131841 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131842 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[1 
(1599816132596858890)]} 0 0
   [junit4]   2> 1131843 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131843 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[1 
(1599816132596858889)]} 0 0
   [junit4]   2> 1131843 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131843 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&_version_=1599816132596858889&wt=json&indent=true}{delete=[1
 (-1599816132596858889)]} 0 0
   [junit4]   2> 1131843 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131843 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132606296064,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131844 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132606296064
   [junit4]   2> 1131845 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@153b678[collection1] main]
   [junit4]   2> 1131846 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131847 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@153b678[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.4.0):c1)))}
   [junit4]   2> 1131847 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 3
   [junit4]   2> 1131848 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[1 
(1599816132596858889)]} 0 0
   [junit4]   2> 1131848 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131848 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&_version_=1599816132596858889&wt=json&indent=true}{delete=[1
 (-1599816132596858889)]} 0 0
   [junit4]   2> 1131848 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131849 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&_version_=1599816132596858895&wt=json&indent=true}{delete=[1
 (-1599816132596858895)]} 0 0
   [junit4]   2> 1131849 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[1 
(1599816132596858894)]} 0 0
   [junit4]   2> 1131849 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131849 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132612587520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131849 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132612587520
   [junit4]   2> 1131850 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@541d8f[collection1] main]
   [junit4]   2> 1131850 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131852 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@541d8f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1131852 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1131853 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{deleteByQuery=id:2 
(-1599816132615733248)} 0 0
   [junit4]   2> 1131853 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131854 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[2 (1599816132616781824)]} 0 0
   [junit4]   2> 1131855 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5bfd48[collection1] realtime]
   [junit4]   2> 1131856 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@187c4dd[collection1] realtime]
   [junit4]   2> 1131857 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{deleteByQuery=id:2 
(-1599816132617830400)} 0 2
   [junit4]   2> 1131857 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=2&wt=xml} status=0 QTime=0
   [junit4]   2> 1131857 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[3 
(1599816132617831500)]} 0 0
   [junit4]   2> 1131857 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[4 
(1599816132617831600)]} 0 0
   [junit4]   2> 1131859 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@e4a3e0[collection1] realtime]
   [junit4]   2> 1131860 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@163384a[collection1] realtime]
   [junit4]   2> 1131861 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&_version_=-1599816132617831550&versions=true&wt=json&indent=true}{deleteByQuery=id:(3
 4 5 6) (-1599816132617831550)} 0 3
   [junit4]   2> 1131861 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=3&wt=xml} status=0 QTime=0
   [junit4]   2> 1131861 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&id=4&wt=xml} status=0 QTime=0
   [junit4]   2> 1131862 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[5 
(1599816132617831601)]} 0 0
   [junit4]   2> 1131862 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 Reordered DBQs detected.  
Update=add{_version_=1599816132617831501,id=6} 
DBQs=[DBQ{version=1599816132617831550,q=id:(3 4 5 6)}]
   [junit4]   2> 1131864 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@91e741[collection1] realtime]
   [junit4]   2> 1131865 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&wt=json&indent=true}{add=[6 
(1599816132617831501)]} 0 2
   [junit4]   2> 1131865 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&id=5&wt=xml} status=0 QTime=0
   [junit4]   2> 1131865 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=6&wt=xml} status=0 QTime=0
   [junit4]   2> 1131865 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132629364736,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131865 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132629364736
   [junit4]   2> 1131866 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1444e6b[collection1] main]
   [junit4]   2> 1131866 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131868 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1444e6b[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.4.0):c2/1:delGen=1)
 Uninverting(_5(7.4.0):c2/1:delGen=1)))}
   [junit4]   2> 1131868 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1131874 INFO  
(TEST-TestRealTimeGet.testVersions-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testVersions
   [junit4]   2> 1131876 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testGetRealtime
   [junit4]   2> 1131876 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1131876 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1de9a5e[collection1] realtime]
   [junit4]   2> 1131877 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
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> 1131877 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132641947648,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131877 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132641947648
   [junit4]   2> 1131877 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@600556[collection1] main]
   [junit4]   2> 1131878 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131879 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@600556[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1131880 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 2
   [junit4]   2> 1131883 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[1 (1599816132646141952)]} 0 2
   [junit4]   2> 1131883 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 1131884 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,+a_f,a_fd,a_fdS+++a_fs,a_fds,a_fdsS,++a_d,a_dd,a_ddS,++a_ds,a_dds,a_ddsS,++a_i,a_id,a_idS+++a_is,a_ids,a_idsS,+++a_l,a_ld,a_ldS+++a_ls,a_lds,a_ldsS&id=1&wt=xml}
 status=0 QTime=0
   [junit4]   2> 1131885 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&ids=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131885 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132650336256,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131886 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132650336256
   [junit4]   2> 1131891 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@e792e9[collection1] main]
   [junit4]   2> 1131892 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131893 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@e792e9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.4.0):c1)))}
   [junit4]   2> 1131893 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 7
   [junit4]   2> 1131894 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 1131895 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,+a_f,a_fd,a_fdS+++a_fs,a_fds,a_fdsS,++a_d,a_dd,a_ddS,++a_ds,a_dds,a_ddsS,++a_i,a_id,a_idS+++a_is,a_ids,a_idsS,+++a_l,a_ld,a_ldS+++a_ls,a_lds,a_ldsS&id=1&wt=xml}
 status=0 QTime=0
   [junit4]   2> 1131895 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131896 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&ids=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131897 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{delete=[1 (-1599816132661870592)]} 0 0
   [junit4]   2> 1131897 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:1&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 1131898 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131898 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&ids=1&wt=xml} status=0 QTime=0
   [junit4]   2> 1131898 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[10 (1599816132663967744)]} 0 0
   [junit4]   2> 1131899 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[11 (1599816132665016320)]} 0 0
   [junit4]   2> 1131900 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&id=10&wt=xml} status=0 QTime=0
   [junit4]   2> 1131902 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@14c1c46[collection1] realtime]
   [junit4]   2> 1131903 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1934583[collection1] realtime]
   [junit4]   2> 1131903 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{deleteByQuery=id:10 foo_s:abcdef (-1599816132666064896)} 0 3
   [junit4]   2> 1131903 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&id=10&wt=xml} status=0 QTime=0
   [junit4]   2> 1131904 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&id=11&wt=xml} status=0 QTime=0
   [junit4]   2> 1131904 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[12 (1599816132670259200)]} 0 0
   [junit4]   2> 1131905 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:12&wt=xml} 
hits=0 status=0 QTime=0
   [junit4]   2> 1131905 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val_ls&id=12&wt=xml} status=0 QTime=0
   [junit4]   2> 1131905 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132671307776,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131905 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132671307776
   [junit4]   2> 1131908 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1abd5b4[collection1] main]
   [junit4]   2> 1131908 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131910 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1abd5b4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(7.4.0):c2/1:delGen=1)
 Uninverting(_8(7.4.0):c1)))}
   [junit4]   2> 1131910 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 1131911 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id,val_ls&id=12&wt=xml} status=0 QTime=0
   [junit4]   2> 1131911 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=id:12&wt=xml} 
hits=1 status=0 QTime=0
   [junit4]   2> 1131912 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[12 (1599816132677599232)]} 0 1
   [junit4]   2> 1131913 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[13 (1599816132679696384)]} 0 0
   [junit4]   2> 1131914 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&id=11&fq=id:11&wt=xml} status=0 QTime=0
   [junit4]   2> 1131916 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@6c59d8[collection1] realtime]
   [junit4]   2> 1131916 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&id=12&fq=id:11&wt=xml} status=0 QTime=2
   [junit4]   2> 1131916 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&id=12&fq=id:13&wt=xml} status=0 QTime=0
   [junit4]   2> 1131917 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&id=12&fq=id:12&wt=xml} status=0 QTime=0
   [junit4]   2> 1131917 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[14 (1599816132683890688)]} 0 0
   [junit4]   2> 1131918 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[15 (1599816132684939264)]} 0 0
   [junit4]   2> 1131922 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@e2e32e[collection1] realtime]
   [junit4]   2> 1131923 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&ids=12,14,13,15&fq=id:[10+TO+14]&fq=id:[13+TO+19]&wt=xml} 
status=0 QTime=4
   [junit4]   2> 1131924 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[16 (1599816132691230720)]} 0 0
   [junit4]   2> 1131925 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{add=[17 (1599816132692279296)]} 0 0
   [junit4]   2> 1131929 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@131dcf9[collection1] realtime]
   [junit4]   2> 1131930 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&ids=17,16,15,14&fq=id:[15+TO+16]&wt=xml} status=0 QTime=4
   [junit4]   2> 1131931 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.c.S.Request [collection1]  webapp=null path=null 
params={qt=/get&fl=id&ids=17,16,15,14&fq={!frange+l%3D15+u%3D16}id&wt=xml} 
status=0 QTime=0
   [junit4]   2> 1131932 INFO  
(TEST-TestRealTimeGet.testGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testGetRealtime
   [junit4]   2> 1131935 INFO  
(TEST-TestRealTimeGet.testStressGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testStressGetRealtime
   [junit4]   2> 1131935 INFO  
(TEST-TestRealTimeGet.testStressGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1131935 INFO  
(TEST-TestRealTimeGet.testStressGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1cf2ccf[collection1] realtime]
   [junit4]   2> 1131936 INFO  
(TEST-TestRealTimeGet.testStressGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9223372036854775807&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{deleteByQuery=*:*
 (-9223372036854775807)} 0 1
   [junit4]   2> 1131937 INFO  
(TEST-TestRealTimeGet.testStressGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1599816132703813632,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131937 INFO  
(TEST-TestRealTimeGet.testStressGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132703813632
   [junit4]   2> 1131937 INFO  
(TEST-TestRealTimeGet.testStressGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1a5a77f[collection1] main]
   [junit4]   2> 1131938 INFO  
(TEST-TestRealTimeGet.testStressGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1131939 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1a5a77f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1131940 INFO  
(TEST-TestRealTimeGet.testStressGetRealtime-seed#[DA5670AD607B5B92]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 3
   [junit4]   2> 1131944 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1440965104&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131944 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=2025682052&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131945 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1225555722&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131945 INFO  (WRITER6) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=1804160920&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131945 INFO  (WRITER1) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1599816132713250817,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131945 INFO  (WRITER1) [    ] o.a.s.u.SolrIndexWriter Calling 
setCommitData with IW:org.apache.solr.update.SolrIndexWriter@cc896e 
commitCommandVersion:1599816132713250817
   [junit4]   2> 1131946 INFO  (WRITER4) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[15 (1599816132712202240)]} 0 2
   [junit4]   2> 1131946 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[3 (1599816132712202241)]} 0 2
   [junit4]   2> 1131946 INFO  (WRITER7) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[5 (1599816132713250819)]} 0 0
   [junit4]   2> 1131946 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=0&versions=true&wt=json&indent=true}{add=[16 
(1599816132713250816)]} 0 1
   [junit4]   2> 1131946 INFO  (WRITER5) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=0&versions=true&wt=json&indent=true}{add=[7 
(1599816132712202243)]} 0 1
   [junit4]   2> 1131949 INFO  (WRITER3) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=0&versions=true&wt=json&indent=true}{add=[9 
(1599816132712202242)]} 0 5
   [junit4]   2> 1131950 INFO  (WRITER3) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=573512673&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131950 INFO  (WRITER6) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[4 (1599816132713250818)]} 0 1
   [junit4]   2> 1131950 INFO  (WRITER6) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=858004936&versions=true&wt=json&indent=true}{} 0 0
   [junit4]   2> 1131956 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=10&fq=id:10} status=0 QTime=0
   [junit4]   2> 1131956 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131956 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131956 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=6} status=0 QTime=0
   [junit4]   2> 1131956 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=1} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131957 INFO  (READER0) [    ] 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> 1131957 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131957 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=15} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131957 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=15} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER1) [    ] 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> 1131957 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131957 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=14} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131957 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131957 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=19} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=19} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=2} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131957 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER2) [    ] 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> 1131957 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=2} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131958 INFO  (READER6) [    ] 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> 1131958 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131958 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131958 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=13} status=0 QTime=0
   [junit4]   2> 1131958 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=3} status=0 QTime=0
   [junit4]   2> 1131957 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> 1131958 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131958 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131958 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=5} status=0 QTime=0
   [junit4]   2> 1131958 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131958 INFO  (READER2) [    ] 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> 1131958 INFO  (READER6) [    ] 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> 1131958 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=19} status=0 QTime=0
   [junit4]   2> 1131958 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131958 INFO  (READER6) [    ] 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> 1131958 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131958 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131958 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131958 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=9} status=0 QTime=0
   [junit4]   2> 1131958 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131958 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131959 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=8} status=0 QTime=0
   [junit4]   2> 1131959 INFO  (READER6) [    ] 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> 1131959 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=2} status=0 QTime=0
   [junit4]   2> 1131959 INFO  (READER6) [    ] 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> 1131959 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=8} status=0 QTime=0
   [junit4]   2> 1131959 INFO  (READER6) [    ] 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> 1131959 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131959 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=6} status=0 QTime=0
   [junit4]   2> 1131958 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131957 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=6} status=0 QTime=0
   [junit4]   2> 1131959 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=15} status=0 QTime=0
   [junit4]   2> 1131959 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131959 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> 1131959 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131959 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131959 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131959 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=5} status=0 QTime=0
   [junit4]   2> 1131959 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131959 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=4} status=0 QTime=0
   [junit4]   2> 1131959 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131960 INFO  (READER2) [    ] 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> 1131960 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131960 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131960 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=17} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=16} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=17} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131960 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=10} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131960 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=13} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=5} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=2} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131960 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=14} status=0 QTime=0
   [junit4]   2> 1131961 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131961 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=0} status=0 QTime=0
   [junit4]   2> 1131961 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=20} status=0 QTime=0
   [junit4]   2> 1131961 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131961 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:8&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131961 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=13&fq=id:13} status=0 QTime=0
   [junit4]   2> 1131961 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131961 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=2} status=0 QTime=0
   [junit4]   2> 1131961 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=13} status=0 QTime=0
   [junit4]   2> 1131961 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=15} status=0 QTime=0
   [junit4]   2> 1131961 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=5} status=0 QTime=0
   [junit4]   2> 1131961 INFO  (READER2) [    ] 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> 1131961 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> 1131962 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131962 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:17&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131962 INFO  (READER2) [    ] 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> 1131962 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=9} status=0 QTime=0
   [junit4]   2> 1131962 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=9} status=0 QTime=0
   [junit4]   2> 1131962 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131962 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=14} status=0 QTime=0
   [junit4]   2> 1131962 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=19} status=0 QTime=0
   [junit4]   2> 1131962 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131962 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131962 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131962 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=7} status=0 QTime=0
   [junit4]   2> 1131963 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=2} status=0 QTime=0
   [junit4]   2> 1131963 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131963 INFO  (READER2) [    ] 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> 1131963 INFO  (READER3) [    ] 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> 1131963 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=2} status=0 QTime=0
   [junit4]   2> 1131963 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131959 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131963 INFO  (READER6) [    ] 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> 1131963 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131963 INFO  (READER4) [    ] 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> 1131963 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=8} status=0 QTime=0
   [junit4]   2> 1131963 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131963 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131963 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=1} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER2) [    ] 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> 1131964 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131960 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131960 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER2) [    ] 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> 1131964 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=10} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=6} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=0} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=0} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=16} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=4} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=13} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131963 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=0} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:10&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131964 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=7} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131963 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131964 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131965 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=14} status=0 QTime=0
   [junit4]   2> 1131965 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131965 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131965 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=16} status=0 QTime=0
   [junit4]   2> 1131965 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=15} status=0 QTime=0
   [junit4]   2> 1131965 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:15&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131965 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131965 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=13} status=0 QTime=0
   [junit4]   2> 1131965 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131965 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=5} status=0 QTime=0
   [junit4]   2> 1131965 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=5} status=0 QTime=0
   [junit4]   2> 1131965 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=17} status=0 QTime=0
   [junit4]   2> 1131966 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=12} status=0 QTime=0
   [junit4]   2> 1131966 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=13} status=0 QTime=0
   [junit4]   2> 1131966 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131966 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:6&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131966 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131964 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131966 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131967 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=18} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=10} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER4) [    ] 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> 1131967 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131967 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=18} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=16} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=5} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=6} status=0 QTime=0
   [junit4]   2> 1131964 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=12} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131968 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131968 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131968 INFO  (READER6) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=4} status=0 QTime=0
   [junit4]   2> 1131968 INFO  (WRITER6) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=0&versions=true&wt=json&indent=true}{add=[6 
(1599816132718493697)]} 0 17
   [junit4]   2> 1131968 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11&fq=id:11} status=0 QTime=0
   [junit4]   2> 1131968 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=12} status=0 QTime=0
   [junit4]   2> 1131968 INFO  (READER0) [    ] 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> 1131968 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131968 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=1} status=0 QTime=0
   [junit4]   2> 1131968 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=14} status=0 QTime=0
   [junit4]   2> 1131968 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=6} status=0 QTime=0
   [junit4]   2> 1131968 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[8 (1599816132714299393)]} 0 22
   [junit4]   2> 1131968 INFO  (WRITER10) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[19 (1599816132718493696)]} 0 18
   [junit4]   2> 1131968 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=20} status=0 QTime=0
   [junit4]   2> 1131969 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=4} status=0 QTime=0
   [junit4]   2> 1131969 INFO  (READER4) [    ] 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> 1131969 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131969 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=8} status=0 QTime=0
   [junit4]   2> 1131969 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131969 INFO  (READER4) [    ] 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> 1131969 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=18} status=0 QTime=0
   [junit4]   2> 1131969 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=19} status=0 QTime=0
   [junit4]   2> 1131969 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=9} status=0 QTime=0
   [junit4]   2> 1131970 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=13} status=0 QTime=0
   [junit4]   2> 1131970 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=7} status=0 QTime=0
   [junit4]   2> 1131970 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=16} status=0 QTime=0
   [junit4]   2> 1131970 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=16} status=0 QTime=0
   [junit4]   2> 1131970 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=7} status=0 QTime=0
   [junit4]   2> 1131970 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=20} status=0 QTime=0
   [junit4]   2> 1131970 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131970 INFO  (READER4) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=13} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=16} status=0 QTime=0
   [junit4]   2> 1131967 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131965 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=2} status=0 QTime=0
   [junit4]   2> 1131971 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131971 INFO  (READER3) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131971 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131971 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:11&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131971 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:9&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131971 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131965 INFO  (READER7) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={wt=json&qt=/get&ids=11} status=0 QTime=0
   [junit4]   2> 1131969 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:20&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131968 INFO  (WRITER8) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={versions=true&wt=json&indent=true}{add=[12 (1599816132714299392)]} 0 22
   [junit4]   2> 1131971 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131971 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> 1131971 INFO  (READER5) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1131971 INFO  (READER5) [    ] 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> 1131975 INFO  (WRITER1) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@2d3601[collection1] main]
   [junit4]   2> 1131978 INFO  (searcherExecutor-4272-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2d3601[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_c(7.4.0):c1)
 Uninverting(_d(7.4.0):c1) Uninverting(_f(7.4.0):c1) Uninverting(_e(7.4.0):c1) 
Uninverting(_g(7.4.0):c1) Uninverting(_h(7.4.0):c5) Uninverting(_i(7.4.0):c1)))}
   [junit4]   2> 1131978 INFO  (WRITER1) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1131978 INFO  (WRITER7) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1599816132717445120,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1131978

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

   >    at org.apache.solr.SolrTestCaseJ4.updateJ(SolrTestCaseJ4.java:1286)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.addAndGetVersion(SolrTestCaseJ4.java:1451)
   [junit4]    >        at 
org.apache.solr.search.TestRealTimeGet$1.run(TestRealTimeGet.java:675)
   [junit4]    > Caused by: java.lang.NullPointerException
   [junit4]    >        at 
org.apache.solr.update.UpdateLog.getCurrentLogSizeFromStream(UpdateLog.java:299)
   [junit4]    >        at 
org.apache.solr.update.DirectUpdateHandler2.getCurrentTLogSize(DirectUpdateHandler2.java:1007)
   [junit4]    >        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc0(DirectUpdateHandler2.java:291)
   [junit4]    >        at 
org.apache.solr.update.DirectUpdateHandler2.addDoc(DirectUpdateHandler2.java:234)
   [junit4]    >        ... 18 more
   [junit4]   2> 1133234 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1133234 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=13381702
   [junit4]   2> 1133234 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1133234 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@b81e9b: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@1e5dd02
   [junit4]   2> 1133241 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1133241 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@13331df: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@a2bc7d
   [junit4]   2> 1133244 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1133244 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@37d562: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@5c84a0
   [junit4]   2> 1133244 INFO  (coreCloseExecutor-4277-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@12f8a8d
   [junit4]   2> 1133244 INFO  (coreCloseExecutor-4277-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=12f8a8d
   [junit4]   2> 1133244 INFO  (coreCloseExecutor-4277-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@3e7d38: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@1dc61d7
   [junit4]   2> 1133252 INFO  (coreCloseExecutor-4277-thread-1) [    
x:collection1] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1133252 INFO  (coreCloseExecutor-4277-thread-1) [    
x:collection1] o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@cc896e commitCommandVersion:0
   [junit4]   2> 1133256 INFO  
(SUITE-TestRealTimeGet-seed#[DA5670AD607B5B92]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{id=PostingsFormat(name=LuceneFixedGap)}, 
docValues:{a_ds=DocValuesFormat(name=Memory), 
val_ls=DocValuesFormat(name=Lucene70), a_fs=DocValuesFormat(name=Lucene70), 
a_d=DocValuesFormat(name=Direct), a_ls=DocValuesFormat(name=Memory), 
a_f=DocValuesFormat(name=Asserting), a_i=DocValuesFormat(name=Lucene70), 
a_idsS=DocValuesFormat(name=Lucene70), a_l=DocValuesFormat(name=Direct), 
a_fds=DocValuesFormat(name=Lucene70), a_id=DocValuesFormat(name=Memory), 
a_dds=DocValuesFormat(name=Memory), a_ids=DocValuesFormat(name=Direct), 
id=DocValuesFormat(name=Lucene70), a_lds=DocValuesFormat(name=Memory), 
val_l=DocValuesFormat(name=Direct), a_is=DocValuesFormat(name=Asserting), 
a_ldsS=DocValuesFormat(name=Direct), a_fdsS=DocValuesFormat(name=Asserting), 
a_fd=DocValuesFormat(name=Asserting), a_ddsS=DocValuesFormat(name=Direct), 
a_dd=DocValuesFormat(name=Direct), a_fdS=DocValuesFormat(name=Lucene70), 
_version_=DocValuesFormat(name=Direct), a_ddS=DocValuesFormat(name=Memory), 
a_idS=DocValuesFormat(name=Direct), a_ld=DocValuesFormat(name=Direct), 
a_ldS=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1642, 
maxMBSortInHeap=7.586860157216316, sim=RandomSimilarity(queryNorm=true): {}, 
locale=pl, timezone=Africa/Kampala
   [junit4]   2> NOTE: Linux 4.13.0-39-generic i386/Oracle Corporation 
1.8.0_162 (32-bit)/cpus=8,threads=1,free=178164368,total=372768768
   [junit4]   2> NOTE: All tests run in this JVM: 
[HdfsChaosMonkeySafeLeaderTest, TestBinaryResponseWriter, 
TestAddFieldRealTimeGet, TestPhraseSuggestions, RuleEngineTest, TestJoin, 
TestNumericRangeQuery32, MergeStrategyTest, 
TestPerFieldSimilarityWithDefaultOverride, SpellCheckCollatorWithCollapseTest, 
TestSSLRandomization, ConnectionReuseTest, UtilsToolTest, 
TestComplexPhraseQParserPlugin, TestManagedSynonymFilterFactory, 
SegmentsInfoRequestHandlerTest, OpenCloseCoreStressTest, ScheduledTriggerTest, 
DocumentAnalysisRequestHandlerTest, ReplaceNodeTest, TestConfigOverlay, 
TestManagedResource, TestReloadAndDeleteDocs, TestPushWriter, TestCSVLoader, 
TestFilteredDocIdSet, ShufflingReplicaListTransformerTest, 
TestHalfAndHalfDocValues, DateRangeFieldTest, TestLRUStatsCache, 
TestPullReplica, MaxSizeAutoCommitTest, TestFastOutputStream, 
JavabinLoaderTest, SolrCloudReportersTest, TestFileDictionaryLookup, 
CollectionReloadTest, CoreAdminRequestStatusTest, TestRandomFaceting, 
TokenizerChainTest, TestJsonFacetRefinement, NodeMutatorTest, UpdateLogTest, 
CdcrBootstrapTest, EchoParamsTest, TestSort, 
TestOverriddenPrefixQueryForCustomFieldType, NodeLostTriggerIntegrationTest, 
MoveReplicaTest, TestRemoteStreaming, RollingRestartTest, SuggesterWFSTTest, 
ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestDynamicFieldResource, 
TestHighlightDedupGrouping, ParsingFieldUpdateProcessorsTest, UpdateParamsTest, 
TestConfig, TestConfigSets, TestQueryWrapperFilter, TestMissingGroups, 
SolrXmlInZkTest, DebugComponentTest, ExplicitHLLTest, TestStressLucene, 
TestSchemalessBufferedUpdates, TestCollectionAPI, 
TestSlowCompositeReaderWrapper, CleanupOldIndexTest, HighlighterConfigTest, 
MBeansHandlerTest, AnalysisErrorHandlingTest, TestConfigSetsAPIExclusivity, 
DeleteStatusTest, ShardRoutingTest, TestLegacyNumericUtils, 
TestSolrCoreSnapshots, MissingSegmentRecoveryTest, IndexBasedSpellCheckerTest, 
StatsComponentTest, TestSchemaResource, TriggerIntegrationTest, 
TestHttpShardHandlerFactory, DistributedQueryComponentCustomSortTest, 
TestLuceneMatchVersion, OutputWriterTest, TestDocumentBuilder, 
TlogReplayBufferedWhileIndexingTest, SolrIndexSplitterTest, 
XmlUpdateRequestHandlerTest, DistributedTermsComponentTest, 
FieldAnalysisRequestHandlerTest, BinaryUpdateRequestHandlerTest, 
ZkStateReaderTest, SolrCoreCheckLockOnStartupTest, TestCloudManagedSchema, 
BlockJoinFacetSimpleTest, TestSystemCollAutoCreate, TestUseDocValuesAsStored2, 
CollectionsAPIDistributedZkTest, ZkSolrClientTest, SynonymTokenizerTest, 
TestCoreBackup, AutoAddReplicasIntegrationTest, TestDocBasedVersionConstraints, 
TestWriterPerf, TestRTimerTree, FullSolrCloudDistribCmdsTest, 
TolerantUpdateProcessorTest, PluginInfoTest, TestMinMaxOnMultiValuedField, 
TestWordDelimiterFilterFactory, TestSolrCloudWithKerberosAlt, 
BigEndianAscendingWordDeserializerTest, PKIAuthenticationIntegrationTest, 
TestJsonRequest, ClassificationUpdateProcessorIntegrationTest, 
TestManagedSchemaThreadSafety, TestSQLHandlerNonCloud, BadIndexSchemaTest, 
ZkCLITest, ConfigSetsAPITest, BlockCacheTest, TestRandomRequestDistribution, 
TestRuleBasedAuthorizationPlugin, TestIBSimilarityFactory, 
HdfsWriteToMultipleCollectionsTest, TestPHPSerializedResponseWriter, 
BooleanFieldTest, HdfsRecoveryZkTest, ClusterStateUpdateTest, TestZkChroot, 
ShardRoutingCustomTest, TestDistributedSearch, TestDistributedGrouping, 
TestHashPartitioner, TermVectorComponentDistributedTest, TestRealTimeGet]
   [junit4] Completed [405/805 (1!)] on J0 in 1.78s, 4 tests, 1 error <<< 
FAILURES!

[...truncated 47726 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1863/consoleText

[repro] Revision: 48a2138e899bca0b8a8485fb7e490a9dc943d997

[repro] Ant options: "-Dargs=-client -XX:+UseParallelGC"
[repro] Repro line:  ant test  -Dtestcase=TestRealTimeGet 
-Dtests.method=testStressGetRealtime -Dtests.seed=DA5670AD607B5B92 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=pl 
-Dtests.timezone=Africa/Kampala -Dtests.asserts=true -Dtests.file.encoding=UTF-8

[repro] ant clean

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

[...truncated 3316 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 
-Dtests.class="*.TestRealTimeGet" -Dtests.showOutput=onerror "-Dargs=-client 
-XX:+UseParallelGC" -Dtests.seed=DA5670AD607B5B92 -Dtests.multiplier=3 
-Dtests.slow=true -Dtests.locale=pl -Dtests.timezone=Africa/Kampala 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8

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

[...truncated 40 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]

Reply via email to