Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1323/

2 tests failed.
FAILED:  org.apache.lucene.search.TestInetAddressRangeQueries.testRandomBig

Error Message:
Java heap space

Stack Trace:
java.lang.OutOfMemoryError: Java heap space
        at 
__randomizedtesting.SeedInfo.seed([6AE5E766A0153ED9:EDB29AE9314C4259]:0)
        at 
org.apache.lucene.util.bkd.HeapPointWriter.writePackedValue(HeapPointWriter.java:107)
        at 
org.apache.lucene.util.bkd.HeapPointWriter.append(HeapPointWriter.java:128)
        at org.apache.lucene.util.bkd.PointReader.split(PointReader.java:74)
        at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1791)
        at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805)
        at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805)
        at org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805)
        at org.apache.lucene.util.bkd.BKDWriter.finish(BKDWriter.java:1008)
        at 
org.apache.lucene.codecs.lucene60.Lucene60PointsWriter.writeField(Lucene60PointsWriter.java:130)
        at 
org.apache.lucene.codecs.PointsWriter.mergeOneField(PointsWriter.java:62)
        at 
org.apache.lucene.codecs.lucene60.Lucene60PointsWriter.merge(Lucene60PointsWriter.java:223)
        at 
org.apache.lucene.index.SegmentMerger.mergePoints(SegmentMerger.java:187)
        at org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:136)
        at 
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4361)
        at org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3933)
        at 
org.apache.lucene.index.SerialMergeScheduler.merge(SerialMergeScheduler.java:40)
        at org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:2082)
        at 
org.apache.lucene.index.IndexWriter.doAfterSegmentFlushed(IndexWriter.java:4992)
        at 
org.apache.lucene.index.DocumentsWriter$MergePendingEvent.process(DocumentsWriter.java:731)
        at 
org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5030)
        at 
org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5021)
        at 
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1573)
        at 
org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1315)
        at 
org.apache.lucene.search.BaseRangeFieldQueryTestCase.verify(BaseRangeFieldQueryTestCase.java:190)
        at 
org.apache.lucene.search.BaseRangeFieldQueryTestCase.doTestRandom(BaseRangeFieldQueryTestCase.java:160)
        at 
org.apache.lucene.search.BaseRangeFieldQueryTestCase.testRandomBig(BaseRangeFieldQueryTestCase.java:75)
        at 
org.apache.lucene.search.TestInetAddressRangeQueries.testRandomBig(TestInetAddressRangeQueries.java:81)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)


FAILED:  org.apache.solr.TestDistributedSearch.test

Error Message:
Captured an uncaught exception in thread: Thread[id=44906, name=Thread-33143, 
state=RUNNABLE, group=TGRP-TestDistributedSearch]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=44906, name=Thread-33143, state=RUNNABLE, 
group=TGRP-TestDistributedSearch]
        at 
__randomizedtesting.SeedInfo.seed([96F460804B43D487:1EA05F5AE5BFB97F]:0)
Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:39331//collection1: 
java.lang.NullPointerException
        at 
org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:41)
        at 
org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:768)
        at 
org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:751)
        at 
org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:423)
        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
        at org.apache.solr.core.SolrCore.execute(SolrCore.java:2487)
        at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722)
        at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528)
        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)
        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)
        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
        at org.eclipse.jetty.server.Server.handle(Server.java:534)
        at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
        at java.lang.Thread.run(Thread.java:748)

        at __randomizedtesting.SeedInfo.seed([96F460804B43D487]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:237)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
        at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
        at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
        at 
org.apache.solr.TestDistributedSearch$1.run(TestDistributedSearch.java:1140)




Build Log:
[...truncated 8331 lines...]
   [junit4] Suite: org.apache.lucene.search.TestInetAddressRangeQueries
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestInetAddressRangeQueries -Dtests.method=testRandomBig 
-Dtests.seed=6AE5E766A0153ED9 -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
 -Dtests.locale=de-DE -Dtests.timezone=America/Cayman -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR    122s J1 | TestInetAddressRangeQueries.testRandomBig <<<
   [junit4]    > Throwable #1: java.lang.OutOfMemoryError: Java heap space
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([6AE5E766A0153ED9:EDB29AE9314C4259]:0)
   [junit4]    >        at 
org.apache.lucene.util.bkd.HeapPointWriter.writePackedValue(HeapPointWriter.java:107)
   [junit4]    >        at 
org.apache.lucene.util.bkd.HeapPointWriter.append(HeapPointWriter.java:128)
   [junit4]    >        at 
org.apache.lucene.util.bkd.PointReader.split(PointReader.java:74)
   [junit4]    >        at 
org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1791)
   [junit4]    >        at 
org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805)
   [junit4]    >        at 
org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805)
   [junit4]    >        at 
org.apache.lucene.util.bkd.BKDWriter.build(BKDWriter.java:1805)
   [junit4]    >        at 
org.apache.lucene.util.bkd.BKDWriter.finish(BKDWriter.java:1008)
   [junit4]    >        at 
org.apache.lucene.codecs.lucene60.Lucene60PointsWriter.writeField(Lucene60PointsWriter.java:130)
   [junit4]    >        at 
org.apache.lucene.codecs.PointsWriter.mergeOneField(PointsWriter.java:62)
   [junit4]    >        at 
org.apache.lucene.codecs.lucene60.Lucene60PointsWriter.merge(Lucene60PointsWriter.java:223)
   [junit4]    >        at 
org.apache.lucene.index.SegmentMerger.mergePoints(SegmentMerger.java:187)
   [junit4]    >        at 
org.apache.lucene.index.SegmentMerger.merge(SegmentMerger.java:136)
   [junit4]    >        at 
org.apache.lucene.index.IndexWriter.mergeMiddle(IndexWriter.java:4361)
   [junit4]    >        at 
org.apache.lucene.index.IndexWriter.merge(IndexWriter.java:3933)
   [junit4]    >        at 
org.apache.lucene.index.SerialMergeScheduler.merge(SerialMergeScheduler.java:40)
   [junit4]    >        at 
org.apache.lucene.index.IndexWriter.maybeMerge(IndexWriter.java:2082)
   [junit4]    >        at 
org.apache.lucene.index.IndexWriter.doAfterSegmentFlushed(IndexWriter.java:4992)
   [junit4]    >        at 
org.apache.lucene.index.DocumentsWriter$MergePendingEvent.process(DocumentsWriter.java:731)
   [junit4]    >        at 
org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5030)
   [junit4]    >        at 
org.apache.lucene.index.IndexWriter.processEvents(IndexWriter.java:5021)
   [junit4]    >        at 
org.apache.lucene.index.IndexWriter.updateDocument(IndexWriter.java:1573)
   [junit4]    >        at 
org.apache.lucene.index.IndexWriter.addDocument(IndexWriter.java:1315)
   [junit4]    >        at 
org.apache.lucene.search.BaseRangeFieldQueryTestCase.verify(BaseRangeFieldQueryTestCase.java:190)
   [junit4]    >        at 
org.apache.lucene.search.BaseRangeFieldQueryTestCase.doTestRandom(BaseRangeFieldQueryTestCase.java:160)
   [junit4]    >        at 
org.apache.lucene.search.BaseRangeFieldQueryTestCase.testRandomBig(BaseRangeFieldQueryTestCase.java:75)
   [junit4]    >        at 
org.apache.lucene.search.TestInetAddressRangeQueries.testRandomBig(TestInetAddressRangeQueries.java:81)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/misc/test/J1/temp/lucene.search.TestInetAddressRangeQueries_6AE5E766A0153ED9-001
   [junit4]   2> NOTE: test params are: 
codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY,
 chunkSize=25137, maxDocsPerChunk=1, blockSize=4), 
termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, 
chunkSize=25137, blockSize=4)), sim=RandomSimilarity(queryNorm=false): {}, 
locale=de-DE, timezone=America/Cayman
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=271326872,total=317718528
   [junit4]   2> NOTE: All tests run in this JVM: [TestLazyDocument, 
TestHighFreqTerms, TestIndexSplitter, TestPKIndexSplitter, 
TestDocValuesStatsCollector, TestInetAddressRangeQueries]
   [junit4] Completed [13/13 (1!)] on J1 in 136.64s, 4 tests, 1 error <<< 
FAILURES!

[...truncated 1 lines...]
   [junit4] JVM J1: stdout was not empty, see: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/misc/test/temp/junit4-J1-20170612_174903_9697726839819003088903.sysout
   [junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
   [junit4] java.lang.OutOfMemoryError: Java heap space
   [junit4] Dumping heap to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/heapdumps/java_pid30482.hprof
 ...
   [junit4] Heap dump file created [571547327 bytes in 3.816 secs]
   [junit4] <<< JVM J1: EOF ----

[...truncated 4500 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/init-core-data-001
   [junit4]   2> 2562074 WARN  
(SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=32 numCloses=32
   [junit4]   2> 2562075 INFO  
(SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 2562076 INFO  
(SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9061)
   [junit4]   2> 2562076 INFO  
(SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 2562188 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/cores/collection1
   [junit4]   2> 2562190 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 2562191 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2e006083{/,null,AVAILABLE}
   [junit4]   2> 2562198 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3e75fd7a{HTTP/1.1,[http/1.1]}{127.0.0.1:34743}
   [junit4]   2> 2562198 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] o.e.j.s.Server 
Started @2565225ms
   [junit4]   2> 2562198 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=34743, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/cores}
   [junit4]   2> 2562199 ERROR 
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2562199 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 2562199 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 2562199 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2562199 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-12T19:44:29.713Z
   [junit4]   2> 2562199 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/solr.xml
   [junit4]   2> 2562218 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 2562263 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c
   [junit4]   2> 2562268 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c
   [junit4]   2> 2562268 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c
   [junit4]   2> 2562270 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/cores
   [junit4]   2> 2562270 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2562271 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2562313 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2562337 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2562440 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2562445 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 2562445 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c
   [junit4]   2> 2562445 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2562446 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 2562447 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=7, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 2562505 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2562505 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2562506 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2562506 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2562507 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=60.5263671875, 
floorSegmentMB=1.130859375, forceMergeDeletesPctAllowed=1.8812980484576602, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2562508 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@47029ba6[collection1] main]
   [junit4]   2> 2562508 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2562517 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2562543 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2562543 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2562543 INFO  (coreLoadExecutor-3750-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1570029353101688832
   [junit4]   2> 2562544 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2562544 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2562544 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2562544 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2562544 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2562544 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2562544 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2562545 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2562546 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@47029ba6[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2562907 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 2562909 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 2562913 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@114d3652{/,null,AVAILABLE}
   [junit4]   2> 2562913 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3a4c461d{HTTP/1.1,[http/1.1]}{127.0.0.1:60942}
   [junit4]   2> 2562914 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] o.e.j.s.Server 
Started @2565941ms
   [junit4]   2> 2562914 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=60942, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/cores}
   [junit4]   2> 2562914 ERROR 
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2562914 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.0.0
   [junit4]   2> 2562915 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 2562915 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2562915 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-12T19:44:30.429Z
   [junit4]   2> 2562915 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 2562920 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 2562955 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c
   [junit4]   2> 2562961 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c
   [junit4]   2> 2562961 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c
   [junit4]   2> 2562962 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/cores
   [junit4]   2> 2562963 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2562963 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 2562995 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 2563018 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2563120 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2563125 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 2563126 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@7d23d27c
   [junit4]   2> 2563126 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2563126 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_96F460804B43D487-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 2563127 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=7, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 2563188 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2563188 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2563189 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2563189 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2563190 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=60.5263671875, 
floorSegmentMB=1.130859375, forceMergeDeletesPctAllowed=1.8812980484576602, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 2563191 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@1e850bca[collection1] main]
   [junit4]   2> 2563191 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2563202 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2563228 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 2563228 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2563228 INFO  (coreLoadExecutor-3761-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1570029353819963392
   [junit4]   2> 2563228 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2563228 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2563228 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2563229 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2563229 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2563229 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2563229 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2563230 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2563231 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1e850bca[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2563488 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 2563491 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1570029354093641728)} 0 1
   [junit4]   2> 2563493 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1570029354096787456)} 0 1
   [junit4]   2> 2563495 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1570029354098884608)]} 0 1
   [junit4]   2> 2563497 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1570029354100981760)]} 0 1
   [junit4]   2> 2563498 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1570029354102030336)]} 0 0
   [junit4]   2> 2563499 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1570029354104127488)]} 0 0
   [junit4]   2> 2563501 INFO  (qtp494089468-13187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1570029354105176064)]} 0 1
   [junit4]   2> 2563502 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1570029354107273216)]} 0 0
   [junit4]   2> 2563503 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1570029354108321792)]} 0 0
   [junit4]   2> 2563504 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1570029354109370368)]} 0 0
   [junit4]   2> 2563505 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1570029354110418944)]} 0 0
   [junit4]   2> 2563506 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1570029354111467520)]} 0 0
   [junit4]   2> 2563507 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1570029354112516096)]} 0 0
   [junit4]   2> 2563509 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1570029354114613248)]} 0 0
   [junit4]   2> 2563510 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1570029354115661824)]} 0 0
   [junit4]   2> 2563511 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1570029354115661824)]} 0 0
   [junit4]   2> 2563512 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1570029354117758976)]} 0 0
   [junit4]   2> 2563513 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1570029354118807552)]} 0 0
   [junit4]   2> 2563514 INFO  (qtp494089468-13187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1570029354119856128)]} 0 0
   [junit4]   2> 2563515 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1570029354119856128)]} 0 0
   [junit4]   2> 2563515 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570029354120904704,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2563515 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6b662ce2 
commitCommandVersion:1570029354120904704
   [junit4]   2> 2563535 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5185a276[collection1] main]
   [junit4]   2> 2563538 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5185a276[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 2563538 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2563538 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 22
   [junit4]   2> 2563539 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570029354146070528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2563539 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3d24057f 
commitCommandVersion:1570029354146070528
   [junit4]   2> 2563547 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@682c3d6f[collection1] main]
   [junit4]   2> 2563549 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@682c3d6f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)))}
   [junit4]   2> 2563549 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2563549 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 10
   [junit4]   2> 2563552 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1570029354157604864)]} 0 2
   [junit4]   2> 2563555 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1570029354160750592)]} 0 1
   [junit4]   2> 2563556 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1570029354162847744)]} 0 0
   [junit4]   2> 2563557 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1570029354163896320)]} 0 0
   [junit4]   2> 2563558 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1570029354164944896)]} 0 0
   [junit4]   2> 2563558 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1570029354165993472)]} 0 0
   [junit4]   2> 2563559 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1570029354167042048)]} 0 0
   [junit4]   2> 2563560 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1570029354167042048)]} 0 0
   [junit4]   2> 2563561 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1570029354168090624)]} 0 0
   [junit4]   2> 2563562 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1570029354169139200)]} 0 0
   [junit4]   2> 2563563 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1570029354170187776)]} 0 0
   [junit4]   2> 2563564 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1570029354171236352)]} 0 0
   [junit4]   2> 2563564 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1570029354172284928)]} 0 0
   [junit4]   2> 2563565 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1570029354173333504)]} 0 0
   [junit4]   2> 2563566 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1570029354173333504)]} 0 0
   [junit4]   2> 2563567 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1570029354174382080)]} 0 0
   [junit4]   2> 2563568 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1570029354175430656)]} 0 0
   [junit4]   2> 2563569 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1570029354176479232)]} 0 0
   [junit4]   2> 2563570 INFO  (qtp494089468-13187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1570029354177527808)]} 0 0
   [junit4]   2> 2563571 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1570029354178576384)]} 0 0
   [junit4]   2> 2563571 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1570029354179624960)]} 0 0
   [junit4]   2> 2563572 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1570029354180673536)]} 0 0
   [junit4]   2> 2563573 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1570029354180673536)]} 0 0
   [junit4]   2> 2563574 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1570029354181722112)]} 0 0
   [junit4]   2> 2563575 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1570029354182770688)]} 0 0
   [junit4]   2> 2563575 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1570029354183819264)]} 0 0
   [junit4]   2> 2563576 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1570029354184867840)]} 0 0
   [junit4]   2> 2563577 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1570029354185916416)]} 0 0
   [junit4]   2> 2563578 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1570029354186964992)]} 0 0
   [junit4]   2> 2563579 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1570029354186964992)]} 0 0
   [junit4]   2> 2563579 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1570029354188013568)]} 0 0
   [junit4]   2> 2563580 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1570029354189062144)]} 0 0
   [junit4]   2> 2563581 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1570029354190110720)]} 0 0
   [junit4]   2> 2563582 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1570029354190110720)]} 0 0
   [junit4]   2> 2563582 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1570029354191159296)]} 0 0
   [junit4]   2> 2563583 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1570029354192207872)]} 0 0
   [junit4]   2> 2563584 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1570029354193256448)]} 0 0
   [junit4]   2> 2563585 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1570029354193256448)]} 0 0
   [junit4]   2> 2563586 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1570029354194305024)]} 0 0
   [junit4]   2> 2563586 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1570029354195353600)]} 0 0
   [junit4]   2> 2563587 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1570029354196402176)]} 0 0
   [junit4]   2> 2563588 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1570029354196402176)]} 0 0
   [junit4]   2> 2563588 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1570029354197450752)]} 0 0
   [junit4]   2> 2563589 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1570029354198499328)]} 0 0
   [junit4]   2> 2563590 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1570029354198499328)]} 0 0
   [junit4]   2> 2563590 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1570029354199547904)]} 0 0
   [junit4]   2> 2563591 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1570029354200596480)]} 0 0
   [junit4]   2> 2563592 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1570029354201645056)]} 0 0
   [junit4]   2> 2563593 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1570029354201645056)]} 0 0
   [junit4]   2> 2563593 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1570029354202693632)]} 0 0
   [junit4]   2> 2563594 INFO  (qtp494089468-13187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1570029354203742208)]} 0 0
   [junit4]   2> 2563595 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1570029354203742208)]} 0 0
   [junit4]   2> 2563595 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1570029354204790784)]} 0 0
   [junit4]   2> 2563596 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1570029354205839360)]} 0 0
   [junit4]   2> 2563597 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1570029354205839360)]} 0 0
   [junit4]   2> 2563597 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1570029354206887936)]} 0 0
   [junit4]   2> 2563598 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1570029354207936512)]} 0 0
   [junit4]   2> 2563599 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1570029354207936512)]} 0 0
   [junit4]   2> 2563600 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1570029354208985088)]} 0 0
   [junit4]   2> 2563600 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1570029354210033664)]} 0 0
   [junit4]   2> 2563601 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1570029354211082240)]} 0 0
   [junit4]   2> 2563602 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1570029354211082240)]} 0 0
   [junit4]   2> 2563603 INFO  (qtp494089468-13187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1570029354212130816)]} 0 0
   [junit4]   2> 2563603 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1570029354213179392)]} 0 0
   [junit4]   2> 2563604 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1570029354214227968)]} 0 0
   [junit4]   2> 2563605 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1570029354214227968)]} 0 0
   [junit4]   2> 2563605 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1570029354215276544)]} 0 0
   [junit4]   2> 2563606 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1570029354216325120)]} 0 0
   [junit4]   2> 2563607 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1570029354216325120)]} 0 0
   [junit4]   2> 2563607 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1570029354217373696)]} 0 0
   [junit4]   2> 2563608 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1570029354218422272)]} 0 0
   [junit4]   2> 2563609 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1570029354218422272)]} 0 0
   [junit4]   2> 2563610 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1570029354219470848)]} 0 0
   [junit4]   2> 2563611 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1570029354220519424)]} 0 0
   [junit4]   2> 2563611 INFO  (qtp494089468-13187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1570029354221568000)]} 0 0
   [junit4]   2> 2563612 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1570029354222616576)]} 0 0
   [junit4]   2> 2563613 INFO  (qtp494089468-13187) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1570029354223665152)]} 0 0
   [junit4]   2> 2563614 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1570029354223665152)]} 0 0
   [junit4]   2> 2563614 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1570029354224713728)]} 0 0
   [junit4]   2> 2563615 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1570029354225762304)]} 0 0
   [junit4]   2> 2563616 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1570029354225762304)]} 0 0
   [junit4]   2> 2563616 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1570029354226810880)]} 0 0
   [junit4]   2> 2563617 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1570029354227859456)]} 0 0
   [junit4]   2> 2563624 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1570029354235199488)]} 0 0
   [junit4]   2> 2563625 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1570029354236248064)]} 0 0
   [junit4]   2> 2563626 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1570029354237296640)]} 0 0
   [junit4]   2> 2563627 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1570029354237296640)]} 0 0
   [junit4]   2> 2563627 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1570029354238345216)]} 0 0
   [junit4]   2> 2563628 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1570029354239393792)]} 0 0
   [junit4]   2> 2563629 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1570029354239393792)]} 0 0
   [junit4]   2> 2563629 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1570029354240442368)]} 0 0
   [junit4]   2> 2563630 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1570029354241490944)]} 0 0
   [junit4]   2> 2563631 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1570029354241490944)]} 0 0
   [junit4]   2> 2563631 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1570029354242539520)]} 0 0
   [junit4]   2> 2563632 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1570029354243588096)]} 0 0
   [junit4]   2> 2563633 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1570029354243588096)]} 0 0
   [junit4]   2> 2563633 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1570029354244636672)]} 0 0
   [junit4]   2> 2563634 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1570029354245685248)]} 0 0
   [junit4]   2> 2563635 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1570029354245685248)]} 0 0
   [junit4]   2> 2563635 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1570029354246733824)]} 0 0
   [junit4]   2> 2563636 INFO  (qtp494089468-13184) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1570029354247782400)]} 0 0
   [junit4]   2> 2563637 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1570029354247782400)]} 0 0
   [junit4]   2> 2563637 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1570029354248830976)]} 0 0
   [junit4]   2> 2563638 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1570029354249879552)]} 0 0
   [junit4]   2> 2563639 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1570029354249879552)]} 0 0
   [junit4]   2> 2563639 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1570029354250928128)]} 0 0
   [junit4]   2> 2563640 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1570029354251976704)]} 0 0
   [junit4]   2> 2563641 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1570029354251976704)]} 0 0
   [junit4]   2> 2563641 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1570029354253025280)]} 0 0
   [junit4]   2> 2563642 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1570029354254073856)]} 0 0
   [junit4]   2> 2563643 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1570029354254073856)]} 0 0
   [junit4]   2> 2563643 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1570029354255122432)]} 0 0
   [junit4]   2> 2563644 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1570029354256171008)]} 0 0
   [junit4]   2> 2563645 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1570029354256171008)]} 0 0
   [junit4]   2> 2563645 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1570029354257219584)]} 0 0
   [junit4]   2> 2563646 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1570029354258268160)]} 0 0
   [junit4]   2> 2563647 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1570029354258268160)]} 0 0
   [junit4]   2> 2563647 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1570029354259316736)]} 0 0
   [junit4]   2> 2563648 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570029354260365312,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2563648 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6b662ce2 
commitCommandVersion:1570029354260365312
   [junit4]   2> 2563661 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@436f7792[collection1] main]
   [junit4]   2> 2563662 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2563663 INFO  
(searcherExecutor-3751-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@436f7792[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 2563663 INFO  (qtp494089468-13185) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 15
   [junit4]   2> 2563664 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1570029354277142528,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2563664 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@3d24057f 
commitCommandVersion:1570029354277142528
   [junit4]   2> 2563676 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@25ecef88[collection1] main]
   [junit4]   2> 2563678 INFO  
(searcherExecutor-3762-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@25ecef88[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):c9)
 Uninverting(_1(7.0.0):c59)))}
   [junit4]   2> 2563679 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2563679 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 14
   [junit4]   2> 2563680 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 2563680 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 2563681 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 2563681 INFO  (qtp494089468-13188) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 2563682 INFO  (qtp494089468-13181) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 2563738 INFO  (qtp1207983899-13199) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671198&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563743 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671198&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563749 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=64
   [junit4]   2> 2563749 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2563753 INFO  (qtp1207983899-13199) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671266&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563754 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671267&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563754 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671268&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563755 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671268&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563756 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671267&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563757 INFO  (qtp1207983899-13203) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2563758 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 2563759 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671266&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563760 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671272&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563761 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=8
   [junit4]   2> 2563763 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671272&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563763 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671273&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563764 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671276&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563764 INFO  (qtp1207983899-13199) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 2563765 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671273&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563766 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 2563767 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671276&ids=145,14,113,136,125,126,115,105,138,8&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563768 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:60942//collection1|[ff01::114]:33332/|[ff01::213]:33332/&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 2563768 INFO  (qtp494089468-13186) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 2563796 INFO  (qtp1207983899-13224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671284&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563798 INFO  (qtp1207983899-13199) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671284&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563806 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=36
   [junit4]   2> 2563807 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2563808 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671321&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563808 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671321&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563809 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671321&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563809 INFO  (qtp1207983899-13225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671323&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563810 INFO  (qtp1207983899-13225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671321&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563811 INFO  (qtp1207983899-13227) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 2563811 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 2563812 INFO  (qtp1207983899-13224) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671323&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563813 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2563816 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671327&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563817 INFO  (qtp1207983899-13225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671330&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563818 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671331&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563818 INFO  (qtp1207983899-13225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671327&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563818 INFO  (qtp1207983899-13199) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=5
   [junit4]   2> 2563819 INFO  (qtp1207983899-13225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671331&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563820 INFO  (qtp1207983899-13199) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671330&ids=143,121,122,2,3,103,127,116,117,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563820 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 2563820 INFO  (qtp1207983899-13205) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 2563822 INFO  (qtp494089468-13187) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 2563824 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671337&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563825 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671337&ids=132,143,133,134,15,4,5,138,107,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563826 INFO  (qtp1207983899-13201) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 2563827 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2563828 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671341&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563829 INFO  (qtp1207983899-13202) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671341&ids=132,143,133,134,15,4,5,138,107,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563829 INFO  (qtp1207983899-13227) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 2563830 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671344&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563831 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671344&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 2563831 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671344&ids=132,143,133,134,15,4,5,138,107,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563832 INFO  (qtp1207983899-13198) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1497296671344&ids=132,143,133,134,15,4,5,138,107,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 2563833 INFO  (qtp1207983899-13225) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 2563833 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 2563834 INFO  (qtp1207983899-13204) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:60942//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1497296671347&isShard=true&wt=javab

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

ection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=1315432679
   [junit4]   2> 2701821 INFO  
(TEST-TestDistributedSearch.test-seed#[96F460804B43D487]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@24b02c83{/,null,UNAVAILABLE}
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 
'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestDistributedSearch -Dtests.method=test 
-Dtests.seed=96F460804B43D487 -Dtests.multiplier=2 -Dtests.nightly=true 
-Dtests.slow=true 
-Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt
 -Dtests.locale=en-ZA -Dtests.timezone=America/Merida -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR    140s J0 | TestDistributedSearch.test <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=44906, name=Thread-33143, state=RUNNABLE, 
group=TGRP-TestDistributedSearch]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([96F460804B43D487:1EA05F5AE5BFB97F]:0)
   [junit4]    > Caused by: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:39331//collection1: 
java.lang.NullPointerException
   [junit4]    >        at 
org.apache.solr.search.grouping.distributed.responseprocessor.StoredFieldsShardResponseProcessor.process(StoredFieldsShardResponseProcessor.java:41)
   [junit4]    >        at 
org.apache.solr.handler.component.QueryComponent.handleGroupedResponses(QueryComponent.java:768)
   [junit4]    >        at 
org.apache.solr.handler.component.QueryComponent.handleResponses(QueryComponent.java:751)
   [junit4]    >        at 
org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:423)
   [junit4]    >        at 
org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
   [junit4]    >        at 
org.apache.solr.core.SolrCore.execute(SolrCore.java:2487)
   [junit4]    >        at 
org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:722)
   [junit4]    >        at 
org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:528)
   [junit4]    >        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:374)
   [junit4]    >        at 
org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:318)
   [junit4]    >        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]    >        at 
org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
   [junit4]    >        at 
org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1699)
   [junit4]    >        at 
org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
   [junit4]    >        at 
org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
   [junit4]    >        at 
org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
   [junit4]    >        at 
org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
   [junit4]    >        at 
org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
   [junit4]    >        at 
org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
   [junit4]    >        at 
org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
   [junit4]    >        at 
org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:462)
   [junit4]    >        at 
org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
   [junit4]    >        at 
org.eclipse.jetty.server.Server.handle(Server.java:534)
   [junit4]    >        at 
org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
   [junit4]    >        at 
org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
   [junit4]    >        at 
org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:273)
   [junit4]    >        at 
org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95)
   [junit4]    >        at 
org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
   [junit4]    >        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
   [junit4]    >        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
   [junit4]    >        at 
org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
   [junit4]    >        at 
org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
   [junit4]    >        at 
org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([96F460804B43D487]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:237)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957)
   [junit4]    >        at 
org.apache.solr.TestDistributedSearch$1.run(TestDistributedSearch.java:1140)
   [junit4]   2> 2701826 INFO  
(SUITE-TestDistributedSearch-seed#[96F460804B43D487]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ------------------------------------------------------- 
Done waiting for tracked resources to be released
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{other_tl1=PostingsFormat(name=Memory), range_facet_l_dv=FSTOrd50, 
SubjectTerms_mfacet=PostingsFormat(name=Memory), 
n_l1=PostingsFormat(name=Memory), a_n_tdt=FSTOrd50, 
multiDefault=BlockTreeOrds(blocksize=128), 
intDefault=PostingsFormat(name=Memory), n_td1=BlockTreeOrds(blocksize=128), 
n_d1=PostingsFormat(name=Memory), n_f1=BlockTreeOrds(blocksize=128), 
range_facet_l=PostingsFormat(name=Memory), n_tl1=BlockTreeOrds(blocksize=128), 
a_s=PostingsFormat(name=LuceneFixedGap), 
oddField_s=PostingsFormat(name=LuceneFixedGap), 
n_tf1=PostingsFormat(name=Memory), lowerfilt1and2=BlockTreeOrds(blocksize=128), 
a_t=PostingsFormat(name=Memory), lowerfilt=BlockTreeOrds(blocksize=128), 
id=FSTOrd50, text=PostingsFormat(name=LuceneFixedGap), 
timestamp=PostingsFormat(name=Memory), 
severity=PostingsFormat(name=LuceneFixedGap), foo_sev_enum=FSTOrd50, 
n_dt1=BlockTreeOrds(blocksize=128), b_n_tdt=PostingsFormat(name=Memory), 
n_ti1=FSTOrd50, rnd_b=FSTOrd50, foo_b=PostingsFormat(name=LuceneFixedGap), 
n_tdt1=PostingsFormat(name=Memory), foo_d=FSTOrd50, 
range_facet_i_dv=PostingsFormat(name=Memory), 
foo_f=PostingsFormat(name=LuceneFixedGap)}, 
docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), 
foo_sev_enum=DocValuesFormat(name=Memory), 
_version_=DocValuesFormat(name=Asserting), 
range_facet_i_dv=DocValuesFormat(name=Asserting), 
intDvoDefault=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Asserting), 
a_i_p=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1808, 
maxMBSortInHeap=5.238133884416585, sim=RandomSimilarity(queryNorm=false): {}, 
locale=en-ZA, timezone=America/Merida
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=2,free=154826872,total=534773760
   [junit4]   2> NOTE: All tests run in this JVM: [TestCrossCoreJoin, 
RAMDirectoryFactoryTest, QueryElevationComponentTest, ConnectionReuseTest, 
TestHttpShardHandlerFactory, StandardRequestHandlerTest, TimeZoneUtilsTest, 
TestFunctionQuery, CopyFieldTest, QueryEqualityTest, 
LeaderElectionIntegrationTest, HLLUtilTest, TestBackupRepositoryFactory, 
CurrencyFieldOpenExchangeTest, TestMaxScoreQueryParser, 
ParsingFieldUpdateProcessorsTest, DistributedVersionInfoTest, 
DocumentAnalysisRequestHandlerTest, TestCodecSupport, TestSmileRequest, 
TestReplicationHandler, TestFaceting, DistributedQueryComponentCustomSortTest, 
TestChildDocTransformer, SolrMetricsIntegrationTest, TestFoldingMultitermQuery, 
SecurityConfHandlerTest, CdcrRequestHandlerTest, 
TestCopyFieldCollectionResource, AnalysisAfterCoreReloadTest, 
TestIntervalFaceting, TestFieldCacheSort, BlobRepositoryCloudTest, 
ReturnFieldsTest, ShufflingReplicaListTransformerTest, 
TestFieldCacheWithThreads, DistributedFacetExistsSmallTest, 
DistributedQueryElevationComponentTest, UUIDFieldTest, TestAnalyzedSuggestions, 
JavabinLoaderTest, TestCoreAdminApis, DisMaxRequestHandlerTest, 
TestCollectionAPI, TestFieldSortValues, SpatialRPTFieldTypeTest, 
DistribDocExpirationUpdateProcessorTest, LeaderFailureAfterFreshStartTest, 
BasicDistributedZkTest, TestSQLHandlerNonCloud, PreAnalyzedFieldTest, 
InfoHandlerTest, TestHighFrequencyDictionaryFactory, 
CollectionTooManyReplicasTest, CloudMLTQParserTest, TestRealTimeGet, 
TestJsonFacetRefinement, TestApiFramework, ConfigureRecoveryStrategyTest, 
TestCSVLoader, TestDistributedSearch]
   [junit4] Completed [442/725 (1!)] on J0 in 139.93s, 1 test, 1 error <<< 
FAILURES!

[...truncated 8420 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:783:
 The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/build.xml:727:
 Some of the tests produced a heap dump, but did not fail. Maybe a suppressed 
OutOfMemoryError? Dumps created:
* java_pid30482.hprof

Total time: 292 minutes 22 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to