You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/07/06 19:57:55 UTC
[JENKINS-EA] Lucene-Solr-7.x-Linux (64bit/jdk-9-ea+175) - Build #
21 - Unstable!
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...]