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

3 tests failed.
FAILED:  
org.apache.lucene.store.TestMockDirectoryWrapper.testThreadSafetyInListAll

Error Message:
Captured an uncaught exception in thread: Thread[id=876, name=Thread-821, 
state=RUNNABLE, group=TGRP-TestMockDirectoryWrapper]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=876, name=Thread-821, state=RUNNABLE, 
group=TGRP-TestMockDirectoryWrapper]
        at 
__randomizedtesting.SeedInfo.seed([E36B24BB1D86E477:F877BB67E87E7100]:0)
Caused by: java.io.UncheckedIOException: Something went wrong when opening: 
file-0
        at __randomizedtesting.SeedInfo.seed([E36B24BB1D86E477]:0)
        at 
org.apache.lucene.store.BaseDirectoryTestCase.lambda$testThreadSafetyInListAll$11(BaseDirectoryTestCase.java:446)
        at java.lang.Thread.run(Thread.java:748)
Caused by: java.io.IOException: MockDirectoryWrapper: file "file-0" is still 
open for writing
        at 
org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:752)
        at 
org.apache.lucene.store.BaseDirectoryTestCase.lambda$testThreadSafetyInListAll$11(BaseDirectoryTestCase.java:440)
        ... 1 more
Caused by: java.lang.RuntimeException: unclosed IndexOutput: file-0
        at 
org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:729)
        at 
org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:668)
        at 
org.apache.lucene.store.BaseDirectoryTestCase.lambda$testThreadSafetyInListAll$8(BaseDirectoryTestCase.java:416)
        ... 1 more


FAILED:  org.apache.solr.TestDistributedGrouping.test

Error Message:
.grouped[a_i1].doclist.maxScore:1.0!=null

Stack Trace:
junit.framework.AssertionFailedError: .grouped[a_i1].doclist.maxScore:1.0!=null
        at 
__randomizedtesting.SeedInfo.seed([F5013E5D69819218:7D550187C77DFFE0]:0)
        at junit.framework.Assert.fail(Assert.java:50)
        at 
org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:928)
        at 
org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:955)
        at 
org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:613)
        at 
org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:589)
        at 
org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:568)
        at 
org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:176)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1034)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.lucene.document.TestLatLonShapeQueries.testRandomBig

Error Message:
Java heap space

Stack Trace:
java.lang.OutOfMemoryError: Java heap space
        at 
__randomizedtesting.SeedInfo.seed([1FFA91F9741BE9B8:98ADEC76E5429538]:0)
        at org.apache.lucene.geo.Polygon.<init>(Polygon.java:94)
        at 
org.apache.lucene.geo.GeoTestUtil.createRegularPolygon(GeoTestUtil.java:384)
        at org.apache.lucene.geo.GeoTestUtil.nextPolygon(GeoTestUtil.java:398)
        at 
org.apache.lucene.document.TestLatLonShapeQueries.doTestRandom(TestLatLonShapeQueries.java:127)
        at 
org.apache.lucene.document.TestLatLonShapeQueries.testRandomBig(TestLatLonShapeQueries.java:107)
        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:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)




Build Log:
[...truncated 2736 lines...]
   [junit4] Suite: org.apache.lucene.store.TestMockDirectoryWrapper
   [junit4] IGNOR/A 0.00s J1 | TestMockDirectoryWrapper.testPendingDeletions
   [junit4]    > Assumption #1: we can only install VirusCheckingFS on an 
FSDirectory
   [junit4] IGNOR/A 0.00s J1 | 
TestMockDirectoryWrapper.testFsyncDoesntCreateNewFiles
   [junit4]    > Assumption #1: test only works for FSDirectory subclasses
   [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=TestMockDirectoryWrapper -Dtests.method=testThreadSafetyInListAll 
-Dtests.seed=E36B24BB1D86E477 -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=ga-IE -Dtests.timezone=Asia/Bangkok -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.38s J1 | 
TestMockDirectoryWrapper.testThreadSafetyInListAll <<<
   [junit4]    > Throwable #1: 
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught 
exception in thread: Thread[id=876, name=Thread-821, state=RUNNABLE, 
group=TGRP-TestMockDirectoryWrapper]
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E36B24BB1D86E477:F877BB67E87E7100]:0)
   [junit4]    > Caused by: java.io.UncheckedIOException: Something went wrong 
when opening: file-0
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([E36B24BB1D86E477]:0)
   [junit4]    >        at 
org.apache.lucene.store.BaseDirectoryTestCase.lambda$testThreadSafetyInListAll$11(BaseDirectoryTestCase.java:446)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > Caused by: java.io.IOException: MockDirectoryWrapper: file 
"file-0" is still open for writing
   [junit4]    >        at 
org.apache.lucene.store.MockDirectoryWrapper.openInput(MockDirectoryWrapper.java:752)
   [junit4]    >        at 
org.apache.lucene.store.BaseDirectoryTestCase.lambda$testThreadSafetyInListAll$11(BaseDirectoryTestCase.java:440)
   [junit4]    >        ... 1 more
   [junit4]    > Caused by: java.lang.RuntimeException: unclosed IndexOutput: 
file-0
   [junit4]    >        at 
org.apache.lucene.store.MockDirectoryWrapper.addFileHandle(MockDirectoryWrapper.java:729)
   [junit4]    >        at 
org.apache.lucene.store.MockDirectoryWrapper.createOutput(MockDirectoryWrapper.java:668)
   [junit4]    >        at 
org.apache.lucene.store.BaseDirectoryTestCase.lambda$testThreadSafetyInListAll$8(BaseDirectoryTestCase.java:416)
   [junit4]    >        ... 1 more
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70), 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@52e244b3),
 locale=ga-IE, timezone=Asia/Bangkok
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_172 (64-bit)/cpus=4,threads=1,free=188746368,total=311951360
   [junit4]   2> NOTE: All tests run in this JVM: [TestAssertingPointsFormat, 
TestExtrasFS, TestRamUsageTesterOnWildAnimals, Nested1, Nested2, Nested3, 
TestGroupFiltering, TestRunWithRestrictedPermissions, TestMockAnalyzer, 
TestMockSynonymFilter, TestAssertingStoredFieldsFormat, 
TestAssertingTermVectorsFormat, TestCompressingTermVectorsFormat, 
TestAssertingLeafReader, TestForceMergePolicy, TestHandleLimitFS, 
TestHandleTrackingFS, TestLeakFS, TestShuffleFS, TestVerboseFS, 
TestVirusCheckingFS, TestBaseExplanationTestCase, TestMockDirectoryWrapper]
   [junit4] Completed [23/41 (1!)] on J1 in 2.50s, 48 tests, 1 error, 2 skipped 
<<< FAILURES!

[...truncated 7420 lines...]
   [junit4] Suite: org.apache.lucene.document.TestLatLonShapeQueries
   [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=TestLatLonShapeQueries -Dtests.method=testRandomBig 
-Dtests.seed=1FFA91F9741BE9B8 -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=sl-SI -Dtests.timezone=SystemV/CST6 -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR    522s J0 | TestLatLonShapeQueries.testRandomBig <<<
   [junit4]    > Throwable #1: java.lang.OutOfMemoryError: Java heap space
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([1FFA91F9741BE9B8:98ADEC76E5429538]:0)
   [junit4]    >        at org.apache.lucene.geo.Polygon.<init>(Polygon.java:94)
   [junit4]    >        at 
org.apache.lucene.geo.GeoTestUtil.createRegularPolygon(GeoTestUtil.java:384)
   [junit4]    >        at 
org.apache.lucene.geo.GeoTestUtil.nextPolygon(GeoTestUtil.java:398)
   [junit4]    >        at 
org.apache.lucene.document.TestLatLonShapeQueries.doTestRandom(TestLatLonShapeQueries.java:127)
   [junit4]    >        at 
org.apache.lucene.document.TestLatLonShapeQueries.testRandomBig(TestLatLonShapeQueries.java:107)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/sandbox/test/J0/temp/lucene.document.TestLatLonShapeQueries_1FFA91F9741BE9B8-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): 
{id=PostingsFormat(name=LuceneFixedGap)}, 
docValues:{shape=DocValuesFormat(name=Lucene70), 
id=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=767, 
maxMBSortInHeap=6.30596522566189, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@546cdd3a),
 locale=sl-SI, timezone=SystemV/CST6
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_172 (64-bit)/cpus=4,threads=1,free=107897848,total=329777152
   [junit4]   2> NOTE: All tests run in this JVM: [TestDocValuesNumbersQuery, 
TestLatLonShapeQueries]
   [junit4] Completed [22/24 (1!)] on J0 in 682.99s, 3 tests, 1 error <<< 
FAILURES!

[...truncated 7 lines...]
   [junit4] JVM J0: stdout was not empty, see: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/sandbox/test/temp/junit4-J0-20180724_112154_6897413939697353549000.sysout
   [junit4] >>> JVM J0 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_pid14715.hprof
 ...
   [junit4] Heap dump file created [492687846 bytes in 3.427 secs]
   [junit4] <<< JVM J0: EOF ----

[...truncated 4050 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedGrouping
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/init-core-data-001
   [junit4]   2> 2354303 WARN  
(SUITE-TestDistributedGrouping-seed#[F5013E5D69819218]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 2354304 INFO  
(SUITE-TestDistributedGrouping-seed#[F5013E5D69819218]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 2354306 INFO  
(SUITE-TestDistributedGrouping-seed#[F5013E5D69819218]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 2354306 INFO  
(SUITE-TestDistributedGrouping-seed#[F5013E5D69819218]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 2354306 INFO  
(SUITE-TestDistributedGrouping-seed#[F5013E5D69819218]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /rmab/
   [junit4]   2> 2354407 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/control/cores/collection1
   [junit4]   2> 2354408 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: 
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 2354409 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2354409 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2354409 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2354411 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1d24121{/rmab,null,AVAILABLE}
   [junit4]   2> 2354411 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@5af68d2{HTTP/1.1,[http/1.1]}{127.0.0.1:38533}
   [junit4]   2> 2354411 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.Server Started @2354495ms
   [junit4]   2> 2354411 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/rmab, 
hostPort=38533, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/control/cores}
   [junit4]   2> 2354411 ERROR 
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2354411 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2354411 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 2354411 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 2354411 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2354412 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-07-24T12:35:11.860Z
   [junit4]   2> 2354412 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/control/solr.xml
   [junit4]   2> 2354415 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 2354417 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2354418 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@37773ff0, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 2354684 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2354684 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 2354701 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37773ff0
   [junit4]   2> 2354711 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37773ff0
   [junit4]   2> 2354712 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37773ff0
   [junit4]   2> 2354713 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
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/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/control/cores
   [junit4]   2> 2354713 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2354714 INFO  (coreLoadExecutor-5140-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> 2354764 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 2354782 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2354905 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2354914 INFO  (coreLoadExecutor-5140-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> 2354915 INFO  (coreLoadExecutor-5140-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@37773ff0
   [junit4]   2> 2354915 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2354915 INFO  (coreLoadExecutor-5140-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/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 2354916 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=16.7060546875, 
floorSegmentMB=1.4755859375, forceMergeDeletesPctAllowed=27.10748892565611, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, 
deletesPctAllowed=33.08235836945266
   [junit4]   2> 2354966 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2354966 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2354967 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2354967 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2354968 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=1.6123046875, 
floorSegmentMB=1.990234375, forceMergeDeletesPctAllowed=26.065547592193596, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.6331618928072869, deletesPctAllowed=46.15406739663682
   [junit4]   2> 2354968 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@74f9c4af[collection1] main]
   [junit4]   2> 2354968 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2354983 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2355073 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2355073 INFO  (coreLoadExecutor-5140-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1606875309692420096
   [junit4]   2> 2355075 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2355075 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2355075 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2355075 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2355075 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2355075 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2355076 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2355076 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2355076 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2355077 INFO  
(searcherExecutor-5141-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@74f9c4af[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2355187 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 2355188 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: 
d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 2355188 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2355188 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2355188 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2355189 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2074a1d0{/rmab,null,AVAILABLE}
   [junit4]   2> 2355189 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7fd0851{HTTP/1.1,[http/1.1]}{127.0.0.1:60875}
   [junit4]   2> 2355189 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.Server Started @2355273ms
   [junit4]   2> 2355189 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/rmab, 
hostPort=60875, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/shard0/cores}
   [junit4]   2> 2355189 ERROR 
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 2355189 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2355189 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 
8.0.0
   [junit4]   2> 2355189 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 2355190 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2355190 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-07-24T12:35:12.638Z
   [junit4]   2> 2355190 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 2355193 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 2355193 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 2355194 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@37773ff0, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 2355339 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 2355339 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 2355355 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37773ff0
   [junit4]   2> 2355363 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37773ff0
   [junit4]   2> 2355363 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37773ff0
   [junit4]   2> 2355365 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
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/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/shard0/cores
   [junit4]   2> 2355365 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 2355366 INFO  (coreLoadExecutor-5154-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> 2355391 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 2355409 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 2355521 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 2355527 INFO  (coreLoadExecutor-5154-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> 2355528 INFO  (coreLoadExecutor-5154-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@37773ff0
   [junit4]   2> 2355528 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 2355528 INFO  (coreLoadExecutor-5154-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/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 2355529 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=16.7060546875, 
floorSegmentMB=1.4755859375, forceMergeDeletesPctAllowed=27.10748892565611, 
segmentsPerTier=23.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, 
deletesPctAllowed=33.08235836945266
   [junit4]   2> 2355599 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 2355599 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 2355600 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 2355600 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 2355601 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=12, maxMergeAtOnceExplicit=41, maxMergedSegmentMB=1.6123046875, 
floorSegmentMB=1.990234375, forceMergeDeletesPctAllowed=26.065547592193596, 
segmentsPerTier=28.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.6331618928072869, deletesPctAllowed=46.15406739663682
   [junit4]   2> 2355601 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@73c33cc[collection1] 
main]
   [junit4]   2> 2355602 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 2355623 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 2355754 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 2355755 INFO  (coreLoadExecutor-5154-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1606875310407548928
   [junit4]   2> 2355756 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 2355757 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 2355757 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 2355757 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 2355757 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 2355757 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 2355757 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 2355758 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 2355758 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 2355758 INFO  
(searcherExecutor-5155-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@73c33cc[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 2356258 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 2356268 INFO  (qtp334330466-19731) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rmab path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1606875310937079808)} 0 8
   [junit4]   2> 2356279 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rmab path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1606875310947565568)} 0 9
   [junit4]   2> 2356280 INFO  (qtp334330466-19727) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1606875310958051328,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2356280 INFO  (qtp334330466-19727) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2356282 INFO  (qtp334330466-19727) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2356282 INFO  (qtp334330466-19727) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rmab path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 1
   [junit4]   2> 2356284 INFO  (qtp653972365-19747) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1606875310962245632,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 2356284 INFO  (qtp653972365-19747) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 2356285 INFO  (qtp653972365-19747) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 2356285 INFO  (qtp653972365-19747) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/rmab path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 1
   [junit4]   2> 2356287 INFO  (qtp334330466-19730) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&distrib=false&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 hits=0 status=0 QTime=2
   [junit4]   2> 2356308 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435713741&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=12
   [junit4]   2> 2356351 INFO  (qtp653972365-19747) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=58
   [junit4]   2> 2356351 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2356362 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435713808&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356362 INFO  (qtp653972365-19750) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356365 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435713811&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356365 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=2
   [junit4]   2> 2356369 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435713809&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356369 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=8
   [junit4]   2> 2356370 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435713817&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356370 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356371 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435713814&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356372 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435713819&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356372 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356376 INFO  (qtp653972365-19750) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=10
   [junit4]   2> 2356377 INFO  (qtp334330466-19730) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&distrib=false&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 hits=0 status=0 QTime=0
   [junit4]   2> 2356527 INFO  (qtp653972365-19750) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&NOW=1532435713826&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356528 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=150
   [junit4]   2> 2356528 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2356530 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&NOW=1532435713977&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356530 INFO  (qtp653972365-19750) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356530 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&NOW=1532435713977&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356530 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&NOW=1532435713977&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356531 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356531 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356531 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&NOW=1532435713979&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356532 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356532 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&NOW=1532435713979&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356535 INFO  (qtp653972365-19773) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=3
   [junit4]   2> 2356537 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&hl=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&NOW=1532435713984&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356537 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::114]:33332/rmab|[ff01::213]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::083]:33332/rmab&hl=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&hl.fl=a_t&rows=100&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356538 INFO  (qtp334330466-19730) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&facet.field=a_t&distrib=false&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true}
 hits=0 status=0 QTime=0
   [junit4]   2> 2356539 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435713986&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356548 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532435713986&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=5
   [junit4]   2> 2356551 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&facet.field=a_t&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=12
   [junit4]   2> 2356559 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2356572 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435714019&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356573 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532435714019&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356573 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&facet.field=a_t&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356574 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435714022&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356575 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532435714022&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356575 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&facet.field=a_t&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356576 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435714024&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356577 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532435714024&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356583 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&facet.field=a_t&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=7
   [junit4]   2> 2356592 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435714039&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356593 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532435714039&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356593 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&facet.field=a_t&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356603 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435714047&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356604 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532435714047&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356604 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&facet.field=a_t&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=5
   [junit4]   2> 2356608 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&NOW=1532435714055&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356608 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&NOW=1532435714055&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356609 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=http://127.0.0.1:60875/rmab/collection1&facet.field=a_t&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&facet=true&wt=javabin&version=2&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356609 INFO  (qtp334330466-19730) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&distrib=false&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1}
 hits=0 status=0 QTime=0
   [junit4]   2> 2356657 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1532435714058&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356658 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=516&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532435714058&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356662 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=51
   [junit4]   2> 2356662 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2356672 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1532435714119&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356673 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=516&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532435714119&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356673 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=1
   [junit4]   2> 2356674 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1532435714122&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356674 INFO  (qtp653972365-19750) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1532435714122&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356675 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=516&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532435714122&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356676 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=1
   [junit4]   2> 2356676 INFO  (qtp653972365-19750) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=516&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532435714122&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356677 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1532435714125&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356678 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=4
   [junit4]   2> 2356678 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=516&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532435714125&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356678 INFO  (qtp653972365-19750) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=1
   [junit4]   2> 2356697 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1532435714144&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356698 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=516&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532435714144&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356699 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=2
   [junit4]   2> 2356710 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&q=*:*&stats=false&NOW=1532435714157&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356710 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=516&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&rows=100&version=2&group.distributed.second=true&q=*:*&stats=true&NOW=1532435714157&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 2356711 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={q=*:*&shards=[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1&stats=true&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&wt=javabin&version=2&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=1
   [junit4]   2> 2356712 INFO  (qtp334330466-19730) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&distrib=false&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&wt=javabin&group.field=a_i1&group=true}
 hits=0 status=0 QTime=0
   [junit4]   2> 2356713 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532435714160&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356713 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&spellcheck=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356713 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2356724 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532435714171&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356724 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&spellcheck=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356731 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532435714179&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356732 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&spellcheck=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356737 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532435714184&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356737 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&spellcheck=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356740 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532435714187&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356740 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&spellcheck=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356744 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532435714191&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356744 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&spellcheck=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356745 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&group.distributed.first=true&distrib=false&qt=spellCheckCompRH&fl=id&group.limit=-1&shards.purpose=2048&start=0&sort=a_i1+asc,+id+asc&shard.url=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&rows=100&version=2&spellcheck.build=true&q=kings&spellcheck=true&NOW=1532435714192&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356745 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={df=subject&qt=spellCheckCompRH&fl=id,a_i1&group.limit=-1&sort=a_i1+asc,+id+asc&rows=100&version=2&spellcheck.build=true&q=kings&shards=[ff01::213]:33332/rmab|[ff01::083]:33332/rmab|http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab&spellcheck=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356750 INFO  (qtp334330466-19730) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&distrib=false&fl=id,a_i1&group.limit=-1&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 hits=0 status=0 QTime=4
   [junit4]   2> 2356751 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&group.truncate=true&NOW=1532435714198&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356756 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&NOW=1532435714198&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356756 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&fl=id,a_i1&group.limit=-1&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&shards=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=5
   [junit4]   2> 2356756 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.BaseDistributedSearchTestCase starting stress...
   [junit4]   2> 2356764 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&group.truncate=true&NOW=1532435714211&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356765 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&NOW=1532435714211&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356765 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&fl=id,a_i1&group.limit=-1&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&shards=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356766 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&group.truncate=true&NOW=1532435714213&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356767 INFO  (qtp653972365-19752) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&NOW=1532435714213&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356767 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&fl=id,a_i1&group.limit=-1&fq=a_s:a&sort=a_i1+asc,+id+asc&rows=100&version=2&q=*:*&shards=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=1
   [junit4]   2> 2356784 INFO  (qtp653972365-19746) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&group.truncate=true&NOW=1532435714231&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356785 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&group.distributed.first=true&distrib=false&fl=id&group.limit=-1&shards.purpose=2048&start=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&rows=100&version=2&q=*:*&group.truncate=true&NOW=1532435714231&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356786 INFO  (qtp653972365-19749) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&df=text&distrib=false&fl=id&group.limit=-1&shards.purpose=20&start=0&f.a_t.facet.mincount=0&fq=a_s:a&sort=a_i1+asc,+id+asc&shard.url=http://127.0.0.1:60875/rmab/collection1|[ff01::114]:33332/rmab|[ff01::083]:33332/rmab&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&NOW=1532435714231&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 2356786 INFO  (qtp653972365-19751) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/rmab path=/select 
params={facet.field=a_t&fl=id,a_i1&group.limit=-1&fq=a_s:a&sort=a_i1+asc,+

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

t id = null] for registry solr.core.collection1 / 
com.codahale.metrics.MetricRegistry@671958e6
   [junit4]   2> 2358271 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1d24121{/rmab,null,UNAVAILABLE}
   [junit4]   2> 2358271 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 2358279 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@7fd0851{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 2358279 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=531639245
   [junit4]   2> 2358279 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 2358279 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@23130a3a: rootName = null, 
domain = solr.node, service url = null, agent id = null] for registry solr.node 
/ com.codahale.metrics.MetricRegistry@61c8af11
   [junit4]   2> 2358286 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 2358286 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@ea6560a: rootName = null, 
domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / 
com.codahale.metrics.MetricRegistry@345bd1d7
   [junit4]   2> 2358289 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 2358289 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@6e8b31d8: rootName = null, 
domain = solr.jetty, service url = null, agent id = null] for registry 
solr.jetty / com.codahale.metrics.MetricRegistry@2bf9cbbe
   [junit4]   2> 2358331 INFO  (coreCloseExecutor-5162-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@c8e24ee
   [junit4]   2> 2358331 INFO  (coreCloseExecutor-5162-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=c8e24ee
   [junit4]   2> 2358331 INFO  (coreCloseExecutor-5162-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter 
[org.apache.solr.metrics.reporters.SolrJmxReporter@40742dcd: rootName = null, 
domain = solr.core.collection1, service url = null, agent id = null] for 
registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@2baa80d2
   [junit4]   2> 2358931 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2074a1d0{/rmab,null,UNAVAILABLE}
   [junit4]   2> 2358931 INFO  
(TEST-TestDistributedGrouping.test-seed#[F5013E5D69819218]) [    ] 
o.e.j.s.session node0 Stopped scavenging
   [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=TestDistributedGrouping -Dtests.method=test 
-Dtests.seed=F5013E5D69819218 -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=sv-SE -Dtests.timezone=Atlantic/South_Georgia 
-Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] FAILURE 4.63s J2 | TestDistributedGrouping.test <<<
   [junit4]    > Throwable #1: junit.framework.AssertionFailedError: 
.grouped[a_i1].doclist.maxScore:1.0!=null
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([F5013E5D69819218:7D550187C77DFFE0]:0)
   [junit4]    >        at junit.framework.Assert.fail(Assert.java:50)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:928)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:955)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:613)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:589)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:568)
   [junit4]    >        at 
org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:176)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1034)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_F5013E5D69819218-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@65a2515b),
 locale=sv-SE, timezone=Atlantic/South_Georgia
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_172 (64-bit)/cpus=4,threads=1,free=145235696,total=527433728
   [junit4]   2> NOTE: All tests run in this JVM: [NodeAddedTriggerTest, 
TestCoreDiscovery, DistributedFacetPivotSmallAdvancedTest, 
ClusterStateUpdateTest, JavabinLoaderTest, TestRequestStatusCollectionAPI, 
TestCoreAdminApis, InfoHandlerTest, TestPrepRecovery, TestSimDistributedQueue, 
CleanupOldIndexTest, TestSolr4Spatial2, LeaderElectionTest, PrimUtilsTest, 
SolrGangliaReporterTest, TestFoldingMultitermQuery, MBeansHandlerTest, 
MetricTriggerIntegrationTest, ImplicitSnitchTest, CurrencyRangeFacetCloudTest, 
RecoveryAfterSoftCommitTest, BasicZkTest, SpellPossibilityIteratorTest, 
TestPivotHelperCode, TestRawResponseWriter, TestJmxIntegration, 
TolerantUpdateProcessorTest, TestBlobHandler, TestSimpleQParserPlugin, 
TestNamedUpdateProcessors, TestMacroExpander, 
ClassificationUpdateProcessorFactoryTest, TestSystemIdResolver, TestJsonFacets, 
TestDocBasedVersionConstraints, MultiSolrCloudTestCaseTest, 
ConnectionManagerTest, TestFaceting, DeleteInactiveReplicaTest, 
SolrMetricManagerTest, OpenCloseCoreStressTest, SimpleFacetsTest, 
TestConfigSetsAPIExclusivity, TestRTGBase, ReplaceNodeNoTargetTest, 
TestSweetSpotSimilarityFactory, TestDirectoryFactory, TestJoin, 
TestSolrConfigHandler, TestSolrIndexConfig, RangeFacetCloudTest, 
HLLSerializationTest, TestFieldCacheVsDocValues, 
DistributedIntervalFacetingTest, TermVectorComponentDistributedTest, 
TestCloudInspectUtil, RequestLoggingTest, OverseerTaskQueueTest, 
SolrShardReporterTest, TestSurroundQueryParser, MetricsHistoryIntegrationTest, 
DocExpirationUpdateProcessorFactoryTest, TestClusterStateProvider, 
RestartWhileUpdatingTest, TestPhraseSuggestions, DistributedMLTComponentTest, 
SmileWriterTest, NodeMarkersRegistrationTest, SoftAutoCommitTest, 
GraphQueryTest, DistributedTermsComponentTest, MetricUtilsTest, 
TestSolrCloudWithDelegationTokens, NotRequiredUniqueKeyTest, HdfsSyncSliceTest, 
AssignTest, TestDeleteCollectionOnDownNodes, BufferStoreTest, UUIDFieldTest, 
TestSortableTextField, HdfsRecoverLeaseTest, HttpPartitionOnCommitTest, 
CloudMLTQParserTest, CustomCollectionTest, EnumFieldTest, 
TestReplicationHandlerBackup, TimeZoneUtilsTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, TestIndexSearcher, SuggesterTest, 
OverseerModifyCollectionTest, TestSolrConfigHandlerConcurrent, TestRTimerTree, 
SolrTestCaseJ4Test, CurrencyFieldTypeTest, ConjunctionSolrSpellCheckerTest, 
AutoscalingHistoryHandlerTest, RAMDirectoryFactoryTest, TestStreamBody, 
DistribCursorPagingTest, ScheduledTriggerIntegrationTest, 
AutoScalingHandlerTest, NoCacheHeaderTest, ConvertedLegacyTest, 
MergeStrategyTest, TestCoreBackup, TestSQLHandler, AddReplicaTest, 
TestTlogReplica, JvmMetricsTest, TestImpersonationWithHadoopAuth, 
TestNumericRangeQuery64, TestIBSimilarityFactory, TestPointFields, 
CollectionsAPISolrJTest, TestExecutePlanAction, TestAuthenticationFramework, 
SOLR749Test, TestNestedUpdateProcessor, WordBreakSolrSpellCheckerTest, 
StatsComponentTest, ZkCLITest, TestLockTree, TestDownShardTolerantSearch, 
TestSolrJ, CoreAdminOperationTest, CollectionsAPIAsyncDistributedZkTest, 
SharedFSAutoReplicaFailoverTest, TestXmlQParser, IndexBasedSpellCheckerTest, 
TestCloudSearcherWarming, XmlInterpolationTest, TestClusterStateMutator, 
PeerSyncWithIndexFingerprintCachingTest, AsyncCallRequestStatusResponseTest, 
EchoParamsTest, MinimalSchemaTest, OutputWriterTest, TestCrossCoreJoin, 
TestCursorMarkWithoutUniqueKey, TestDistributedGrouping]
   [junit4] Completed [401/825 (1!)] on J2 in 4.65s, 1 test, 1 failure <<< 
FAILURES!

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

Total time: 329 minutes 42 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