Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/21/
Java: 64bit/jdk-9-ea+175 -XX:-UseCompressedOops -XX:+UseG1GC 
--illegal-access=deny

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

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

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=17187, name=READER0, state=RUNNABLE, 
group=TGRP-TestStressRecovery]
        at 
__randomizedtesting.SeedInfo.seed([3BF52F5799DE54A8:81CF460A0636EBA6]:0)
Caused by: java.lang.RuntimeException: java.lang.AssertionError
        at __randomizedtesting.SeedInfo.seed([3BF52F5799DE54A8]:0)
        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:331)
Caused by: java.lang.AssertionError
        at org.junit.Assert.fail(Assert.java:92)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at org.junit.Assert.assertTrue(Assert.java:54)
        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:318)




Build Log:
[...truncated 12369 lines...]
   [junit4] Suite: org.apache.solr.search.TestStressRecovery
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_3BF52F5799DE54A8-001/init-core-data-001
   [junit4]   2> 1374389 WARN  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
   [junit4]   2> 1374389 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1374389 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, 
clientAuth=0.0/0.0)
   [junit4]   2> 1374389 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1374389 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-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> 1374400 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1374410 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1374455 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1374481 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a31652
   [junit4]   2> 1374484 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a31652
   [junit4]   2> 1374484 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@24a31652
   [junit4]   2> 1374486 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
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> 1374506 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1374516 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1374565 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
   [junit4]   2> 1374569 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
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> 1374569 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@24a31652
   [junit4]   2> 1374569 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1374569 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
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/J2/temp/solr.search.TestStressRecovery_3BF52F5799DE54A8-001/init-core-data-001/]
   [junit4]   2> 1374570 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=25, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8343140506447789]
   [junit4]   2> 1374571 WARN  (coreLoadExecutor-5876-thread-1) [    ] 
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> 1374591 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1374591 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1374592 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1374592 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1374593 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=19, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1374593 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@48103f24[collection1] main]
   [junit4]   2> 1374593 WARN  (coreLoadExecutor-5876-thread-1) [    ] 
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> 1374593 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1374594 INFO  (searcherExecutor-5877-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@48103f24[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1374594 INFO  (coreLoadExecutor-5876-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1572202938488586240
   [junit4]   2> 1374596 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1374597 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting testStressRecovery
   [junit4]   2> 1374597 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 1374597 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@54f75c24[collection1] realtime]
   [junit4]   2> 1374597 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
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> 1374597 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1572202938491731968,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1374597 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b669e32 
commitCommandVersion:1572202938491731968
   [junit4]   2> 1374598 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1d5a7de0[collection1] main]
   [junit4]   2> 1374598 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1374599 INFO  (searcherExecutor-5877-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1d5a7de0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1374599 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 1
   [junit4]   2> 1374603 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-2&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0
 (-2)]} 0 0
   [junit4]   2> 1374603 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-1&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10
 (-1)]} 0 0
   [junit4]   2> 1374603 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-3&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0
 (-3)]} 0 0
   [junit4]   2> 1374603 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-4&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[14
 (-4)]} 0 0
   [junit4]   2> 1374603 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1572202938498023424,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1374603 INFO  (WRITER0) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1374608 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 4
   [junit4]   2> 1374608 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374608 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, 
tlog=tlog{file=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_3BF52F5799DE54A8-001/init-core-data-001/tlog/tlog.0000000000000000001
 refcount=1}}
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (WRITER1) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374609 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-5&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[14
 (-5)]} 0 0
   [junit4]   2> 1374609 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374609 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-6&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[14
 (-6)]} 0 0
   [junit4]   2> 1374609 INFO  (READER0) [    ] 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> 1374609 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> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374609 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> 1374609 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374610 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER2) [    ] 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> 1374610 INFO  (READER2) [    ] 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> 1374610 INFO  (READER0) [    ] 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> 1374610 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 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> 1374610 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 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> 1374610 INFO  (READER0) [    ] 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> 1374610 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[14 
(7)]} 0 0
   [junit4]   2> 1374610 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> 1374610 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (WRITER1) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374610 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374610 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374610 INFO  (READER1) [    ] 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> 1374610 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374611 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-8&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[14
 (-8)]} 0 0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374611 INFO  (READER0) [    ] 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> 1374611 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-9&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[11
 (-9)]} 0 0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER2) [    ] 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> 1374611 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-10&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[11
 (-10)]} 0 0
   [junit4]   2> 1374611 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374611 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374611 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> 1374611 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374611 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374611 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> 1374611 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374611 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374611 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> 1374612 INFO  (READER1) [    ] 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> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-12&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[8
 (-12)]} 0 0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-13&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[2
 (-13)]} 0 0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[0 
(14)]} 0 0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:2&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-15&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[12
 (-15)]} 0 0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-16&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[12
 (-16)]} 0 0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374612 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374612 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> 1374612 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> 1374612 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374613 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> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-17&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[9
 (-17)]} 0 0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-18&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[2
 (-18)]} 0 0
   [junit4]   2> 1374613 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> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-19&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
 (-19)]} 0 0
   [junit4]   2> 1374613 INFO  (READER1) [    ] 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> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-20&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[8
 (-20)]} 0 0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-21&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[8
 (-21)]} 0 0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-22&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[12
 (-22)]} 0 0
   [junit4]   2> 1374613 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> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[12 
(23)]} 0 0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374613 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374613 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374614 INFO  (READER1) [    ] 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> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-24&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[12
 (-24)]} 0 0
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:14&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374611 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:7&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374611 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-11&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[8
 (-11)]} 0 0
   [junit4]   2> 1374614 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374611 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-26&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[6
 (-26)]} 0 0
   [junit4]   2> 1374614 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> 1374614 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374614 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374614 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[6 
(25)]} 0 0
   [junit4]   2> 1374614 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> 1374614 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-27&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[6
 (-27)]} 0 0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-28&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
 (-28)]} 0 0
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[3 
(29)]} 0 0
   [junit4]   2> 1374614 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-30&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
 (-30)]} 0 0
   [junit4]   2> 1374615 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-32&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[6
 (-32)]} 0 0
   [junit4]   2> 1374615 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374615 INFO  (WRITER1) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374615 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374614 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374615 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[3 
(31)]} 0 0
   [junit4]   2> 1374615 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374615 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374615 INFO  (READER1) [    ] 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> 1374615 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> 1374615 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-33&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
 (-33)]} 0 0
   [junit4]   2> 1374615 INFO  (WRITER2) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374615 INFO  (READER1) [    ] 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> 1374616 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374615 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-34&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[13
 (-34)]} 0 0
   [junit4]   2> 1374616 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1374615 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-36&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[13
 (-36)]} 0 0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] 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> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374616 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> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374616 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> 1374616 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> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374616 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> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:13&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:3&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-35&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[13
 (-35)]} 0 0
   [junit4]   2> 1374617 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> 1374616 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 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> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (WRITER2) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374616 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[3 
(37)]} 0 0
   [junit4]   2> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-38&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
 (-38)]} 0 0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER1) [    ] 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> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] 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> 1374617 INFO  (READER1) [    ] 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> 1374617 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> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-39&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1
 (-39)]} 0 0
   [junit4]   2> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (WRITER1) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374617 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374618 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374617 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 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> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER0) [    ] 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> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (WRITER2) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-40&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[1
 (-40)]} 0 0
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 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> 1374618 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374618 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=13&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374618 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 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> 1374618 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[7 
(41)]} 0 0
   [junit4]   2> 1374618 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 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> 1374618 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-44&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[7
 (-44)]} 0 0
   [junit4]   2> 1374618 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[14 
(43)]} 0 0
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374618 INFO  (WRITER1) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374618 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374618 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER0) [    ] 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> 1374618 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[12 
(42)]} 0 0
   [junit4]   2> 1374619 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374619 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-45&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[3
 (-45)]} 0 0
   [junit4]   2> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:12&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374618 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-46&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[14
 (-46)]} 0 0
   [junit4]   2> 1374618 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 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> 1374619 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-47&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[5
 (-47)]} 0 0
   [junit4]   2> 1374619 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
BUFFERING replay: false
   [junit4]   2> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER2) [    ] 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> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374619 INFO  (READER2) [    ] 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> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374619 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374627 INFO  (READER1) [    ] 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> 1374627 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374628 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374628 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> 1374628 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374628 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374627 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:4&omitHeader=true&wt=json} hits=0 status=0 
QTime=7
   [junit4]   2> 1374628 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374628 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[5 
(48)]} 0 0
   [junit4]   2> 1374628 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374628 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374628 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
APPLYING_BUFFERED replay: false
   [junit4]   2> 1374628 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374628 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374628 WARN  (recoveryExecutor-5879-thread-1) [    ] 
o.a.s.u.UpdateLog Starting log replay 
tlog{file=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.search.TestStressRecovery_3BF52F5799DE54A8-001/init-core-data-001/tlog/tlog.0000000000000000001
 refcount=2} active=true starting pos=101 inSortedOrder=false
   [junit4]   2> 1374628 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
APPLYING_BUFFERED replay: false
   [junit4]   2> 1374628 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374628 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-49&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[5
 (-49)]} 0 0
   [junit4]   2> 1374628 INFO  (WRITER0) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
APPLYING_BUFFERED replay: false
   [junit4]   2> 1374628 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374628 INFO  (WRITER2) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
APPLYING_BUFFERED replay: false
   [junit4]   2> 1374628 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[14 
(50)]} 0 0
   [junit4]   2> 1374628 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374629 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-51&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[14
 (-51)]} 0 0
   [junit4]   2> 1374629 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[14 
(52)]} 0 0
   [junit4]   2> 1374629 INFO  (WRITER1) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
APPLYING_BUFFERED replay: false
   [junit4]   2> 1374629 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[0 
(54)]} 0 0
   [junit4]   2> 1374629 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374629 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-55&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0
 (-55)]} 0 0
   [junit4]   2> 1374629 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-56&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[0
 (-56)]} 0 0
   [junit4]   2> 1374629 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[4 
(57)]} 0 0
   [junit4]   2> 1374629 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={_version_=-58&update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{delete=[10
 (-58)]} 0 0
   [junit4]   2> 1374629 INFO  (WRITER1) [    ] 
o.a.s.u.p.DistributedUpdateProcessor Ignoring commit while not ACTIVE - state: 
APPLYING_BUFFERED replay: false
   [junit4]   2> 1374629 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1374629 INFO  (recoveryExecutor-5879-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{flags=2,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1374628 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 INFO  (recoveryExecutor-5879-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b669e32 commitCommandVersion:0
   [junit4]   2> 1374629 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374628 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=2&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 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> 1374629 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=1&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=9&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=11&wt=json} status=0 QTime=0
   [junit4]   2> 1374629 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> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:1&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER1) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374628 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER0) [    ] 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> 1374630 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> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=0&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=7&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=5&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=10&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER0) [    ] 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> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=14&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374630 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> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=8&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 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> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=6&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=4&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=12&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER2) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0 QTime=0
   [junit4]   2> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={q=id:0&omitHeader=true&wt=json} hits=0 status=0 
QTime=0
   [junit4]   2> 1374630 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> 1374630 INFO  (READER0) [    ] o.a.s.c.S.Request [collection1] 
 webapp=null path=null params={qt=/get&ids=3&wt=json} status=0

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

ionsHandler uncaughtException
   [junit4]   2> WARNING: Uncaught exception in thread: 
Thread[READER0,5,TGRP-TestStressRecovery]
   [junit4]   2> java.lang.RuntimeException: java.lang.AssertionError
   [junit4]   2>        at 
__randomizedtesting.SeedInfo.seed([3BF52F5799DE54A8]:0)
   [junit4]   2>        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:331)
   [junit4]   2> Caused by: java.lang.AssertionError
   [junit4]   2>        at org.junit.Assert.fail(Assert.java:92)
   [junit4]   2>        at org.junit.Assert.assertTrue(Assert.java:43)
   [junit4]   2>        at org.junit.Assert.assertTrue(Assert.java:54)
   [junit4]   2>        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:318)
   [junit4]   2> 
   [junit4]   2> 1377857 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1572202941905895427,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1377857 INFO  (WRITER0) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={update.distrib=FROMLEADER&versions=true&wt=json&indent=true}{add=[6 
(2594)]} 0 3
   [junit4]   2> 1377857 WARN  (recoveryExecutor-5879-thread-1) [    ] 
o.a.s.u.UpdateLog Log replay finished. recoveryInfo=RecoveryInfo{adds=7 
deletes=22 deleteByQuery=0 errors=0 positionOfStart=137}
   [junit4]   2> 1377858 INFO  (WRITER2) [    ] o.a.s.s.SolrIndexSearcher 
Opening [Searcher@76626526[collection1] main]
   [junit4]   2> 1377858 INFO  (WRITER2) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1377859 INFO  (searcherExecutor-5877-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@76626526[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5s(7.1.0):C5/4:delGen=2)
 Uninverting(_5z(7.1.0):C7/5:delGen=1) Uninverting(_60(7.1.0):C1)))}
   [junit4]   2> 1377859 INFO  (WRITER2) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 6
   [junit4]   2> 1377860 INFO  (WRITER1) [    ] o.a.s.u.DirectUpdateHandler2 
start 
commit{_version_=1572202941913235456,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false}
   [junit4]   2> 1377860 INFO  (WRITER1) [    ] o.a.s.u.DirectUpdateHandler2 
end_commit_flush
   [junit4]   2> 1377860 INFO  (WRITER1) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null 
params={}{commit=} 0 0
   [junit4]   2> 1377860 INFO  
(TEST-TestStressRecovery.testStressRecovery-seed#[3BF52F5799DE54A8]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending testStressRecovery
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestStressRecovery 
-Dtests.method=testStressRecovery -Dtests.seed=3BF52F5799DE54A8 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=teo-UG 
-Dtests.timezone=PLT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   3.27s J2 | TestStressRecovery.testStressRecovery <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=17187, name=READER0, state=RUNNABLE, 
group=TGRP-TestStressRecovery]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3BF52F5799DE54A8:81CF460A0636EBA6]:0)
   [junit4]    > Caused by: java.lang.RuntimeException: java.lang.AssertionError
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([3BF52F5799DE54A8]:0)
   [junit4]    >        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:331)
   [junit4]    > Caused by: java.lang.AssertionError
   [junit4]    >        at 
org.apache.solr.search.TestStressRecovery$2.run(TestStressRecovery.java:318)
   [junit4]   2> 1377861 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1377861 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=273642727
   [junit4]   2> 1377861 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1377862 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1377863 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1377863 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1377863 INFO  (coreCloseExecutor-5882-thread-1) [    ] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@483b4a7f
   [junit4]   2> 1377863 INFO  (coreCloseExecutor-5882-thread-1) [    ] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1377863 INFO  (coreCloseExecutor-5882-thread-1) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@4b669e32 commitCommandVersion:0
   [junit4]   2> 1377864 INFO  (coreCloseExecutor-5882-thread-1) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1211845247
   [junit4]   2> 1377865 INFO  
(SUITE-TestStressRecovery-seed#[3BF52F5799DE54A8]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=false): {}, locale=teo-UG, timezone=PLT
   [junit4]   2> NOTE: Linux 4.10.0-21-generic amd64/Oracle Corporation 9 
(64-bit)/cpus=8,threads=1,free=149426136,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: 
[DistributedExpandComponentTest, TestManagedStopFilterFactory, 
TestNumericTokenStream, RecoveryZkTest, TestSSLRandomization, V2StandaloneTest, 
HardAutoCommitTest, TestQueryUtils, ClassificationUpdateProcessorTest, 
TestGraphTermsQParserPlugin, TestSolrConfigHandler, 
TestReplicationHandlerBackup, PreAnalyzedFieldTest, 
TestSuggestSpellingConverter, TestMultiValuedNumericRangeQuery, 
DateMathParserTest, TestPerFieldSimilarityWithDefaultOverride, 
SharedFSAutoReplicaFailoverTest, TestDocBasedVersionConstraints, 
TestRandomCollapseQParserPlugin, SuggesterFSTTest, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestTrie, SmileWriterTest, 
TestSQLHandler, PolyFieldTest, SolrMetricReporterTest, AtomicUpdatesTest, 
BJQParserTest, SimplePostToolTest, WrapperMergePolicyFactoryTest, 
HdfsUnloadDistributedZkTest, SSLMigrationTest, HdfsBasicDistributedZk2Test, 
MoveReplicaHDFSTest, TestStressUserVersions, TestSolrCoreSnapshots, 
DirectUpdateHandlerTest, TestSchemaVersionResource, SuggesterTest, 
ResponseHeaderTest, TestRandomFlRTGCloud, SolrTestCaseJ4Test, 
BasicDistributedZk2Test, XsltUpdateRequestHandlerTest, RegexBoostProcessorTest, 
DeleteReplicaTest, TestSolrCoreParser, TestLMJelinekMercerSimilarityFactory, 
HdfsLockFactoryTest, NodeMutatorTest, TestDistributedMissingSort, 
TestJmxIntegration, CoreMergeIndexesAdminHandlerTest, HLLSerializationTest, 
TestConfigSetsAPI, TestDynamicFieldCollectionResource, TestRecovery, 
DistributedSuggestComponentTest, RankQueryTest, AutoCommitTest, 
NotRequiredUniqueKeyTest, TestLeaderElectionWithEmptyReplica, 
CurrencyFieldTypeTest, TestRuleBasedAuthorizationPlugin, 
ReplicaListTransformerTest, TestReload, StatsComponentTest, TestCryptoKeys, 
TestLRUStatsCache, HdfsRecoverLeaseTest, DistribCursorPagingTest, 
TestTolerantUpdateProcessorRandomCloud, ForceLeaderTest, 
TestMaxTokenLenTokenizer, TestSolrDeletionPolicy2, TestNumericTerms32, 
VersionInfoTest, TestIndexSearcher, TestDFRSimilarityFactory, 
TestGroupingSearch, OverseerCollectionConfigSetProcessorTest, 
TestSolrCLIRunExample, TestUnifiedSolrHighlighter, OverseerTest, 
TestPayloadCheckQParserPlugin, ExitableDirectoryReaderTest, 
CdcrReplicationHandlerTest, TestLegacyNumericRangeQueryBuilder, TestUtils, 
TestCloudRecovery, TestTolerantSearch, CleanupOldIndexTest, 
CollectionStateFormat2Test, AddBlockUpdateTest, HdfsSyncSliceTest, 
DateFieldTest, TestCloudManagedSchema, TestConfigsApi, BitVectorTest, 
UnloadDistributedZkTest, TestCustomStream, TermsComponentTest, 
TimeZoneUtilsTest, TestZkChroot, TestRawTransformer, 
TestBackupRepositoryFactory, StatsReloadRaceTest, ReplicationFactorTest, 
HdfsChaosMonkeySafeLeaderTest, PingRequestHandlerTest, 
DeleteInactiveReplicaTest, PrimitiveFieldTypeTest, TestRecoveryHdfs, 
LeaderFailoverAfterPartitionTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, 
CollectionsAPIDistributedZkTest, FullSolrCloudDistribCmdsTest, 
LeaderElectionTest, TestRandomDVFaceting, TestSolr4Spatial, 
QueryElevationComponentTest, PeerSyncTest, TestFunctionQuery, TestBadConfig, 
ShowFileRequestHandlerTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, 
SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, 
CoreAdminHandlerTest, SolrRequestParserTest, TestFoldingMultitermQuery, 
SpatialFilterTest, SuggesterWFSTTest, TestCSVLoader, 
SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, 
TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, 
SortByFunctionTest, DistanceFunctionTest, SolrInfoBeanTest, 
IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, 
TestReversedWildcardFilterFactory, TestQueryTypes, RequestHandlersTest, 
PathHierarchyTokenizerFactoryTest, RequiredFieldsTest, 
FastVectorHighlighterTest, ReturnFieldsTest, QueryParsingTest, 
UniqFieldsUpdateProcessorFactoryTest, TestPartialUpdateDeduplication, 
SearchHandlerTest, SOLR749Test, TestQuerySenderListener, 
AlternateDirectoryTest, TestQuerySenderNoQuery, CopyFieldTest, 
ResponseLogComponentTest, BadComponentTest, TestStressRecovery]
   [junit4] Completed [442/727 (1!)] on J2 in 3.49s, 1 test, 1 error <<< 
FAILURES!

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

Reply via email to