Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1364/
Java: 64bit/jdk-13.0.2 -XX:-UseCompressedOops -XX:+UseG1GC

3 tests failed.
FAILED:  org.apache.solr.TestDistributedGrouping.test

Error Message:
Error from server at http://127.0.0.1:50028/jo/collection1: 
org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this 
request exceeded:[http://[::1]:4/jo, http://[::1]:6/jo, http://[::1]:8/jo]

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:50028/jo/collection1: 
org.apache.solr.client.solrj.SolrServerException: Time allowed to handle this 
request exceeded:[http://[::1]:4/jo, http://[::1]:6/jo, http://[::1]:8/jo]
        at 
__randomizedtesting.SeedInfo.seed([9D7405E02D050FA0:15203A3A83F96258]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
        at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1003)
        at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:1018)
        at 
org.apache.solr.BaseDistributedSearchTestCase.queryServer(BaseDistributedSearchTestCase.java:625)
        at 
org.apache.solr.TestDistributedGrouping.simpleQuery(TestDistributedGrouping.java:455)
        at 
org.apache.solr.TestDistributedGrouping.test(TestDistributedGrouping.java:443)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1117)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
        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:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        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 
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.base/java.lang.Thread.run(Thread.java:830)


FAILED:  org.apache.solr.cloud.PeerSyncReplicationTest.test

Error Message:
There are still nodes recoverying - waited for 30 seconds

Stack Trace:
java.lang.AssertionError: There are still nodes recoverying - waited for 30 
seconds
        at 
__randomizedtesting.SeedInfo.seed([9D7405E02D050FA0:15203A3A83F96258]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:197)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:1074)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1631)
        at 
org.apache.solr.cloud.PeerSyncReplicationTest.bringUpDeadNodeAndEnsureNoReplication(PeerSyncReplicationTest.java:318)
        at 
org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:171)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
        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:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        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 
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.base/java.lang.Thread.run(Thread.java:830)


FAILED:  
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds

Error Message:
soft530 after hard529 but no hard530: 1697877358618671 !<= 1697876836416865

Stack Trace:
java.lang.AssertionError: soft530 after hard529 but no hard530: 
1697877358618671 !<= 1697876836416865
        at 
__randomizedtesting.SeedInfo.seed([9D7405E02D050FA0:CCA0FC609C763F07]:0)
        at org.junit.Assert.fail(Assert.java:88)
        at org.junit.Assert.assertTrue(Assert.java:41)
        at 
org.apache.solr.update.SoftAutoCommitTest.doTestSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:268)
        at 
org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:168)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.base/java.lang.reflect.Method.invoke(Method.java:567)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
        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:947)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
        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 
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.base/java.lang.Thread.run(Thread.java:830)




Build Log:
[...truncated 14724 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedGrouping
   [junit4]   2> 1114407 INFO  
(SUITE-TestDistributedGrouping-seed#[9D7405E02D050FA0]-worker) [     ] 
o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to 
test-framework derived value of 
'/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 1114407 INFO  
(SUITE-TestDistributedGrouping-seed#[9D7405E02D050FA0]-worker) [     ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1114408 INFO  
(SUITE-TestDistributedGrouping-seed#[9D7405E02D050FA0]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Created dataDir: 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/data-dir-112-001
   [junit4]   2> 1114408 WARN  
(SUITE-TestDistributedGrouping-seed#[9D7405E02D050FA0]-worker) [     ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 1114408 INFO  
(SUITE-TestDistributedGrouping-seed#[9D7405E02D050FA0]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1114409 INFO  
(SUITE-TestDistributedGrouping-seed#[9D7405E02D050FA0]-worker) [     ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, 
clientAuth=0.0/0.0) w/ MAC_OS_X supressed clientAuth
   [junit4]   2> 1114409 INFO  
(SUITE-TestDistributedGrouping-seed#[9D7405E02D050FA0]-worker) [     ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /jo/
   [junit4]   2> 1115079 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1115081 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1115081 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1115081 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1115081 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8
   [junit4]   2> 1115084 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1115084 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1115084 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1115085 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1f6044c{/jo,null,AVAILABLE}
   [junit4]   2> 1115086 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@23cbff{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:50010}
   [junit4]   2> 1115086 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.Server Started @1115351ms
   [junit4]   2> 1115086 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/jo, hostPort=50010, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/control/cores}
   [junit4]   2> 1115087 ERROR 
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1115087 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1115087 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.7.0
   [junit4]   2> 1115087 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1115087 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
   [junit4]   2> 1115087 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-08-09T08:05:34.328229Z
   [junit4]   2> 1115087 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/control/solr.xml
   [junit4]   2> 1115089 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1115089 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1115091 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2dae0110, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1115171 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 1115173 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@53fb735[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1115173 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@53fb735[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1115176 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@5a851901[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1115176 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@5a851901[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1115177 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.CoreContainer Not all security plugins configured!  
authentication=disabled authorization=disabled.  Solr is only as secure as you 
make it. Consider configuring authentication/authorization before exposing Solr 
to users internal or external.  See https://s.apache.org/solrsecurity for more 
info
   [junit4]   2> 1115198 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 1115198 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 1115216 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2dae0110
   [junit4]   2> 1115223 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2dae0110
   [junit4]   2> 1115223 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2dae0110
   [junit4]   2> 1115224 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/control/cores
   [junit4]   2> 1115225 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1115232 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
   [junit4]   2> 1115247 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1115251 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 1115339 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field 
id
   [junit4]   2> 1115347 INFO  (coreLoadExecutor-7533-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@2dae0110
   [junit4]   2> 1115347 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/control/cores/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1115349 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=35, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=52.1787109375, 
floorSegmentMB=0.49609375, forceMergeDeletesPctAllowed=15.087363493954939, 
segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8207219833838079, deletesPctAllowed=25.234087770821347
   [junit4]   2> 1115416 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1115416 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1115418 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1115418 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1115418 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=68.947265625, 
floorSegmentMB=0.826171875, forceMergeDeletesPctAllowed=10.372341335103409, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=23.201823399593074
   [junit4]   2> 1115419 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1115448 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1115555 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 1115555 INFO  (coreLoadExecutor-7533-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1674534280019050496
   [junit4]   2> 1115556 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1115556 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1115556 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1115556 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1115557 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1115557 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1115558 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1115558 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1115558 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1115559 INFO  
(searcherExecutor-7535-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 1115896 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1115898 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, 
break even is size 23
   [junit4]   2> 1115898 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1115898 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1115898 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: 
a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 13.0.2+8
   [junit4]   2> 1115901 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1115901 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1115901 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1115902 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@1ed697af{/jo,null,AVAILABLE}
   [junit4]   2> 1115905 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.AbstractConnector Started ServerConnector@45ff3871{HTTP/1.1, (http/1.1, 
h2c)}{127.0.0.1:50028}
   [junit4]   2> 1115905 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.s.Server Started @1116169ms
   [junit4]   2> 1115905 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/jo, hostPort=50028, 
coreRootDirectory=/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/shard0/cores}
   [junit4]   2> 1115906 ERROR 
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1115906 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter Using logger factory 
org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1115906 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.7.0
   [junit4]   2> 1115906 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1115907 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr
   [junit4]   2> 1115907 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2020-08-09T08:05:35.148103Z
   [junit4]   2> 1115907 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1115924 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1115924 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1115928 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@2dae0110, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1116044 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: 
WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
   [junit4]   2> 1116046 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@5d711900[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1116046 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@5d711900[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1116050 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.u.s.S.config Trusting all certificates configured for 
Client@3ef25e71[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1116050 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for 
Client@3ef25e71[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1116051 WARN  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.CoreContainer Not all security plugins configured!  
authentication=disabled authorization=disabled.  Solr is only as secure as you 
make it. Consider configuring authentication/authorization before exposing Solr 
to users internal or external.  See https://s.apache.org/solrsecurity for more 
info
   [junit4]   2> 1116072 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 
transient cores
   [junit4]   2> 1116072 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history 
in memory.
   [junit4]   2> 1116095 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2dae0110
   [junit4]   2> 1116104 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2dae0110
   [junit4]   2> 1116104 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2dae0110
   [junit4]   2> 1116106 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/shard0/cores
   [junit4]   2> 1116106 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1116112 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
   [junit4]   2> 1116125 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1116132 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 1116234 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field 
id
   [junit4]   2> 1116245 INFO  (coreLoadExecutor-7551-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@2dae0110
   [junit4]   2> 1116245 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/shard0/cores/collection1],
 
dataDir=[/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J2/temp/solr.TestDistributedGrouping_9D7405E02D050FA0-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1116246 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=35, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=52.1787109375, 
floorSegmentMB=0.49609375, forceMergeDeletesPctAllowed=15.087363493954939, 
segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.8207219833838079, deletesPctAllowed=25.234087770821347
   [junit4]   2> 1116317 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1116317 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1116321 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1116321 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1116322 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=40, maxMergeAtOnceExplicit=31, maxMergedSegmentMB=68.947265625, 
floorSegmentMB=0.826171875, forceMergeDeletesPctAllowed=10.372341335103409, 
segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, 
deletesPctAllowed=23.201823399593074
   [junit4]   2> 1116323 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1116350 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1116444 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 1116444 INFO  (coreLoadExecutor-7551-thread-1) [    
x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1674534280951234560
   [junit4]   2> 1116445 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1116446 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1116446 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1116446 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1116446 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1116446 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1116446 INFO  
(TEST-TestDistributedGrouping.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1116447 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1116447 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1116447 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1116448 INFO  
(searcherExecutor-7553-thread-1-processing-x:collection1) [    x:collection1 ] 
o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 1116451 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1674534280955428864)} 0 2
   [junit4]   2> 1116456 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1674534280960671744)} 0 3
   [junit4]   2> 1116457 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 0
   [junit4]   2> 1116459 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 1
   [junit4]   2> 1116461 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=0
   [junit4]   2> 1116478 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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:50028/jo/collection1|[::1]:8/jo&rows=100&rid=127.0.0.1-51882&version=2&q=*:*&omitHeader=false&NOW=1596960335708&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=5
   [junit4]   2> 1116484 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo path=/select 
params={q=*:*&shards=http://127.0.0.1:50028/jo/collection1|[::1]:8/jo&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}
 rid=127.0.0.1-51882 status=0 QTime=17
   [junit4]   2> 1116486 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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> 1116490 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=[::1]:6/jo|http://127.0.0.1:50028/jo/collection1|[::1]:4/jo&rows=100&rid=127.0.0.1-51884&version=2&q=*:*&omitHeader=false&NOW=1596960335728&isShard=true&hl.fl=a_t&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 1116490 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo path=/select 
params={q=*:*&shards=[::1]:6/jo|http://127.0.0.1:50028/jo/collection1|[::1]:4/jo&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}
 rid=127.0.0.1-51884 status=0 QTime=3
   [junit4]   2> 1116493 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=1
   [junit4]   2> 1116497 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=[::1]:4/jo|http://127.0.0.1:50028/jo/collection1|[::1]:8/jo&rows=100&rid=127.0.0.1-51886&version=2&q=*:*&omitHeader=false&NOW=1596960335736&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 1116502 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=[::1]:4/jo|http://127.0.0.1:50028/jo/collection1|[::1]:8/jo&rows=100&rid=127.0.0.1-51886&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&omitHeader=false&NOW=1596960335736&isShard=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=3
   [junit4]   2> 1116505 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo path=/select 
params={q=*:*&shards=[::1]:4/jo|http://127.0.0.1:50028/jo/collection1|[::1]:8/jo&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}
 rid=127.0.0.1-51886 status=0 QTime=10
   [junit4]   2> 1116507 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=1
   [junit4]   2> 1116510 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=[::1]:6/jo|[::1]:4/jo|http://127.0.0.1:50028/jo/collection1&rows=100&rid=127.0.0.1-51888&version=2&q=*:*&stats=false&omitHeader=false&NOW=1596960335750&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 1116511 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=[::1]:6/jo|[::1]:4/jo|http://127.0.0.1:50028/jo/collection1&rows=100&rid=127.0.0.1-51888&version=2&group.distributed.second=true&q=*:*&stats=true&omitHeader=false&NOW=1596960335750&isShard=true&wt=javabin&group.field=a_i1&group=true&stats.field=a_i1}
 status=0 QTime=0
   [junit4]   2> 1116512 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo path=/select 
params={q=*:*&shards=[::1]:6/jo|[::1]:4/jo|http://127.0.0.1:50028/jo/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}
 rid=127.0.0.1-51888 status=0 QTime=2
   [junit4]   2> 1116514 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=1
   [junit4]   2> 1116516 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=http://127.0.0.1:50028/jo/collection1|[::1]:4/jo|[::1]:6/jo&rows=100&rid=127.0.0.1-51890&version=2&spellcheck.build=true&q=kings&spellcheck=true&omitHeader=false&NOW=1596960335756&isShard=true&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 1116516 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=http://127.0.0.1:50028/jo/collection1|[::1]:4/jo|[::1]:6/jo&spellcheck=true&wt=javabin&group.field=a_i1&group=true}
 rid=127.0.0.1-51890 status=0 QTime=1
   [junit4]   2> 1116520 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=2
   [junit4]   2> 1116523 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=[::1]:4/jo|http://127.0.0.1:50028/jo/collection1|[::1]:6/jo&rows=100&rid=127.0.0.1-51892&version=2&q=*:*&group.truncate=true&omitHeader=false&NOW=1596960335761&isShard=true&facet=false&wt=javabin&group.field=a_i1&group=true}
 status=0 QTime=0
   [junit4]   2> 1116524 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=20&fq=a_s:a&shard.url=[::1]:4/jo|http://127.0.0.1:50028/jo/collection1|[::1]:6/jo&rid=127.0.0.1-51892&wt=javabin&group.field=a_i1&group=true&facet.field=a_t&group.limit=-1&start=0&f.a_t.facet.mincount=0&sort=a_i1+asc,+id+asc&rows=100&version=2&group.distributed.second=true&q=*:*&f.a_t.facet.limit=160&group.truncate=true&omitHeader=false&NOW=1596960335761&isShard=true&facet=true}
 status=0 QTime=0
   [junit4]   2> 1116525 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.c.S.Request [collection1]  webapp=/jo 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=[::1]:4/jo|http://127.0.0.1:50028/jo/collection1|[::1]:6/jo&group.truncate=true&facet=true&wt=javabin&group.field=a_i1&group=true}
 rid=127.0.0.1-51892 status=0 QTime=4
   [junit4]   2> 1116532 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[1 (1674534281038266368)]} 0 5
   [junit4]   2> 1116534 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[1 (1674534281044557824)]} 0 1
   [junit4]   2> 1116536 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[2 (1674534281046654976)]} 0 0
   [junit4]   2> 1116537 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[2 (1674534281047703552)]} 0 0
   [junit4]   2> 1116539 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[3 (1674534281048752128)]} 0 1
   [junit4]   2> 1116540 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[3 (1674534281050849280)]} 0 0
   [junit4]   2> 1116541 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[4 (1674534281051897856)]} 0 0
   [junit4]   2> 1116542 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[4 (1674534281053995008)]} 0 0
   [junit4]   2> 1116543 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[5 (1674534281053995008)]} 0 0
   [junit4]   2> 1116543 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[5 (1674534281055043584)]} 0 0
   [junit4]   2> 1116544 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[6 (1674534281056092160)]} 0 0
   [junit4]   2> 1116545 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[6 (1674534281057140736)]} 0 0
   [junit4]   2> 1116546 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[7 (1674534281058189312)]} 0 0
   [junit4]   2> 1116547 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[7 (1674534281059237888)]} 0 0
   [junit4]   2> 1116549 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[8 (1674534281061335040)]} 0 0
   [junit4]   2> 1116552 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[8 (1674534281064480768)]} 0 0
   [junit4]   2> 1116554 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[9 (1674534281066577920)]} 0 0
   [junit4]   2> 1116556 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[9 (1674534281068675072)]} 0 0
   [junit4]   2> 1116558 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[10 (1674534281069723648)]} 0 0
   [junit4]   2> 1116559 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[10 (1674534281071820800)]} 0 0
   [junit4]   2> 1116560 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[11 (1674534281072869376)]} 0 0
   [junit4]   2> 1116562 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[11 (1674534281074966528)]} 0 0
   [junit4]   2> 1116565 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[12 (1674534281078112256)]} 0 0
   [junit4]   2> 1116566 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[12 (1674534281079160832)]} 0 0
   [junit4]   2> 1116569 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[14 (1674534281081257984)]} 0 0
   [junit4]   2> 1116570 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[14 (1674534281083355136)]} 0 0
   [junit4]   2> 1116571 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[15 (1674534281084403712)]} 0 0
   [junit4]   2> 1116572 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[15 (1674534281085452288)]} 0 0
   [junit4]   2> 1116574 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[16 (1674534281087549440)]} 0 0
   [junit4]   2> 1116576 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[16 (1674534281088598016)]} 0 0
   [junit4]   2> 1116578 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[17 (1674534281091743744)]} 0 0
   [junit4]   2> 1116579 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[17 (1674534281092792320)]} 0 0
   [junit4]   2> 1116581 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[18 (1674534281093840896)]} 0 0
   [junit4]   2> 1116583 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[18 (1674534281095938048)]} 0 0
   [junit4]   2> 1116585 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[19 (1674534281098035200)]} 0 0
   [junit4]   2> 1116586 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[19 (1674534281100132352)]} 0 0
   [junit4]   2> 1116589 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[20 (1674534281102229504)]} 0 1
   [junit4]   2> 1116591 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[20 (1674534281104326656)]} 0 0
   [junit4]   2> 1116592 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[21 (1674534281105375232)]} 0 0
   [junit4]   2> 1116607 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[21 (1674534281122152448)]} 0 0
   [junit4]   2> 1116608 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[22 (1674534281123201024)]} 0 0
   [junit4]   2> 1116609 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[22 (1674534281124249600)]} 0 0
   [junit4]   2> 1116615 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[23 (1674534281130541056)]} 0 0
   [junit4]   2> 1116621 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[23 (1674534281136832512)]} 0 4
   [junit4]   2> 1116622 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[100 (1674534281137881088)]} 0 0
   [junit4]   2> 1116623 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[100 (1674534281138929664)]} 0 0
   [junit4]   2> 1116624 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[101 (1674534281139978240)]} 0 0
   [junit4]   2> 1116625 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[101 (1674534281141026816)]} 0 0
   [junit4]   2> 1116627 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[102 (1674534281142075392)]} 0 0
   [junit4]   2> 1116635 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[102 (1674534281150464000)]} 0 0
   [junit4]   2> 1116638 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[103 (1674534281153609728)]} 0 0
   [junit4]   2> 1116648 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[103 (1674534281157804032)]} 0 7
   [junit4]   2> 1116651 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[104 (1674534281167241216)]} 0 0
   [junit4]   2> 1116654 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[104 (1674534281170386944)]} 0 0
   [junit4]   2> 1116656 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[105 (1674534281172484096)]} 0 0
   [junit4]   2> 1116657 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[105 (1674534281174581248)]} 0 0
   [junit4]   2> 1116658 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[106 (1674534281175629824)]} 0 0
   [junit4]   2> 1116660 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[106 (1674534281177726976)]} 0 0
   [junit4]   2> 1116662 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[107 (1674534281179824128)]} 0 0
   [junit4]   2> 1116664 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[107 (1674534281180872704)]} 0 0
   [junit4]   2> 1116665 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[108 (1674534281182969856)]} 0 0
   [junit4]   2> 1116668 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[108 (1674534281185067008)]} 0 0
   [junit4]   2> 1116669 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[109 (1674534281187164160)]} 0 0
   [junit4]   2> 1116671 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[109 (1674534281188212736)]} 0 0
   [junit4]   2> 1116673 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[110 (1674534281190309888)]} 0 1
   [junit4]   2> 1116675 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[110 (1674534281192407040)]} 0 0
   [junit4]   2> 1116676 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[111 (1674534281194504192)]} 0 0
   [junit4]   2> 1116677 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[111 (1674534281195552768)]} 0 0
   [junit4]   2> 1116678 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[112 (1674534281196601344)]} 0 0
   [junit4]   2> 1116680 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[112 (1674534281197649920)]} 0 0
   [junit4]   2> 1116681 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[113 (1674534281199747072)]} 0 0
   [junit4]   2> 1116684 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[113 (1674534281200795648)]} 0 1
   [junit4]   2> 1116686 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[114 (1674534281203941376)]} 0 1
   [junit4]   2> 1116691 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[114 (1674534281210232832)]} 0 0
   [junit4]   2> 1116693 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[115 (1674534281212329984)]} 0 0
   [junit4]   2> 1116694 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[115 (1674534281213378560)]} 0 0
   [junit4]   2> 1116696 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[116 (1674534281214427136)]} 0 0
   [junit4]   2> 1116697 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[116 (1674534281216524288)]} 0 0
   [junit4]   2> 1116699 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[117 (1674534281217572864)]} 0 0
   [junit4]   2> 1116701 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[117 (1674534281219670016)]} 0 1
   [junit4]   2> 1116703 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[118 (1674534281221767168)]} 0 0
   [junit4]   2> 1116705 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[118 (1674534281223864320)]} 0 0
   [junit4]   2> 1116706 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[119 (1674534281225961472)]} 0 0
   [junit4]   2> 1116708 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[119 (1674534281227010048)]} 0 0
   [junit4]   2> 1116709 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[120 (1674534281229107200)]} 0 0
   [junit4]   2> 1116710 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[120 (1674534281230155776)]} 0 0
   [junit4]   2> 1116711 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[121 (1674534281231204352)]} 0 0
   [junit4]   2> 1116721 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[121 (1674534281241690112)]} 0 0
   [junit4]   2> 1116723 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[122 (1674534281242738688)]} 0 0
   [junit4]   2> 1116724 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[122 (1674534281243787264)]} 0 0
   [junit4]   2> 1116724 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[123 (1674534281244835840)]} 0 0
   [junit4]   2> 1116725 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[123 (1674534281245884416)]} 0 0
   [junit4]   2> 1116728 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[124 (1674534281247981568)]} 0 0
   [junit4]   2> 1116730 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[124 (1674534281251127296)]} 0 0
   [junit4]   2> 1116731 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[125 (1674534281252175872)]} 0 0
   [junit4]   2> 1116733 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[125 (1674534281254273024)]} 0 0
   [junit4]   2> 1116736 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[126 (1674534281256370176)]} 0 1
   [junit4]   2> 1116738 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[126 (1674534281259515904)]} 0 0
   [junit4]   2> 1116739 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[127 (1674534281260564480)]} 0 0
   [junit4]   2> 1116742 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[127 (1674534281263710208)]} 0 0
   [junit4]   2> 1116744 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[128 (1674534281265807360)]} 0 0
   [junit4]   2> 1116745 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[128 (1674534281266855936)]} 0 0
   [junit4]   2> 1116747 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[129 (1674534281267904512)]} 0 0
   [junit4]   2> 1116748 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[129 (1674534281270001664)]} 0 0
   [junit4]   2> 1116750 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[130 (1674534281271050240)]} 0 0
   [junit4]   2> 1116751 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[130 (1674534281273147392)]} 0 0
   [junit4]   2> 1116754 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[131 (1674534281276293120)]} 0 0
   [junit4]   2> 1116755 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[131 (1674534281277341696)]} 0 0
   [junit4]   2> 1116756 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[132 (1674534281278390272)]} 0 0
   [junit4]   2> 1116757 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[132 (1674534281279438848)]} 0 0
   [junit4]   2> 1116758 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[133 (1674534281280487424)]} 0 0
   [junit4]   2> 1116759 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[133 (1674534281281536000)]} 0 0
   [junit4]   2> 1116761 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[134 (1674534281282584576)]} 0 0
   [junit4]   2> 1116762 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[134 (1674534281284681728)]} 0 0
   [junit4]   2> 1116763 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[135 (1674534281285730304)]} 0 0
   [junit4]   2> 1116765 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[135 (1674534281286778880)]} 0 0
   [junit4]   2> 1116767 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[136 (1674534281288876032)]} 0 0
   [junit4]   2> 1116768 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[136 (1674534281290973184)]} 0 0
   [junit4]   2> 1116769 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[137 (1674534281292021760)]} 0 0
   [junit4]   2> 1116771 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[137 (1674534281293070336)]} 0 0
   [junit4]   2> 1116772 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[138 (1674534281294118912)]} 0 0
   [junit4]   2> 1116773 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[138 (1674534281296216064)]} 0 0
   [junit4]   2> 1116774 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[139 (1674534281296216064)]} 0 0
   [junit4]   2> 1116775 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[139 (1674534281298313216)]} 0 0
   [junit4]   2> 1116778 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[140 (1674534281300410368)]} 0 1
   [junit4]   2> 1116779 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[140 (1674534281302507520)]} 0 0
   [junit4]   2> 1116780 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[141 (1674534281303556096)]} 0 0
   [junit4]   2> 1116782 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[141 (1674534281304604672)]} 0 0
   [junit4]   2> 1116784 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[142 (1674534281306701824)]} 0 0
   [junit4]   2> 1116785 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[142 (1674534281308798976)]} 0 0
   [junit4]   2> 1116787 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[143 (1674534281310896128)]} 0 0
   [junit4]   2> 1116789 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[143 (1674534281312993280)]} 0 0
   [junit4]   2> 1116791 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[144 (1674534281315090432)]} 0 0
   [junit4]   2> 1116792 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[144 (1674534281316139008)]} 0 0
   [junit4]   2> 1116794 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[145 (1674534281317187584)]} 0 0
   [junit4]   2> 1116795 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[145 (1674534281319284736)]} 0 0
   [junit4]   2> 1116798 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[146 (1674534281321381888)]} 0 0
   [junit4]   2> 1116799 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[146 (1674534281323479040)]} 0 0
   [junit4]   2> 1116800 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[147 (1674534281324527616)]} 0 0
   [junit4]   2> 1116808 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[147 (1674534281325576192)]} 0 6
   [junit4]   2> 1116810 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[148 (1674534281335013376)]} 0 0
   [junit4]   2> 1116812 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[148 (1674534281336061952)]} 0 0
   [junit4]   2> 1116813 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[149 (1674534281338159104)]} 0 0
   [junit4]   2> 1116814 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[149 (1674534281339207680)]} 0 0
   [junit4]   2> 1116815 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[500 (1674534281340256256)]} 0 0
   [junit4]   2> 1116818 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[500 (1674534281342353408)]} 0 1
   [junit4]   2> 1116820 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[501 (1674534281345499136)]} 0 0
   [junit4]   2> 1116822 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[501 (1674534281346547712)]} 0 0
   [junit4]   2> 1116824 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[502 (1674534281348644864)]} 0 0
   [junit4]   2> 1116825 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[502 (1674534281350742016)]} 0 0
   [junit4]   2> 1116827 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[503 (1674534281351790592)]} 0 0
   [junit4]   2> 1116829 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[503 (1674534281353887744)]} 0 0
   [junit4]   2> 1116838 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[504 (1674534281355984896)]} 0 8
   [junit4]   2> 1116844 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[504 (1674534281366470656)]} 0 3
   [junit4]   2> 1116850 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[505 (1674534281372762112)]} 0 3
   [junit4]   2> 1116855 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[505 (1674534281378004992)]} 0 4
   [junit4]   2> 1116857 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[506 (1674534281383247872)]} 0 0
   [junit4]   2> 1116858 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[506 (1674534281385345024)]} 0 0
   [junit4]   2> 1116865 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[507 (1674534281385345024)]} 0 6
   [junit4]   2> 1116871 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[507 (1674534281395830784)]} 0 4
   [junit4]   2> 1116872 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[508 (1674534281400025088)]} 0 0
   [junit4]   2> 1116873 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[508 (1674534281401073664)]} 0 0
   [junit4]   2> 1116874 INFO  (qtp1077241847-11171) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[509 (1674534281402122240)]} 0 0
   [junit4]   2> 1116884 INFO  (qtp1461654741-11192) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[509 (1674534281403170816)]} 0 8
   [junit4]   2> 1116885 INFO  (qtp1077241847-11172) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[510 (1674534281413656576)]} 0 0
   [junit4]   2> 1116888 INFO  (qtp1461654741-11190) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[510 (1674534281416802304)]} 0 0
   [junit4]   2> 1116889 INFO  (qtp1077241847-11169) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[511 (1674534281417850880)]} 0 0
   [junit4]   2> 1116890 INFO  (qtp1461654741-11193) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[511 (1674534281417850880)]} 0 0
   [junit4]   2> 1116891 INFO  (qtp1077241847-11170) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/jo path=/update 
params={wt=javabin&version=2}{add=[512 (1674534281418899456)]} 0 0
   [junit4]   2> 1116891 INFO  (qtp1461654741-11191) [    x:collection1 ] 
o.a.s.u.p.LogUpdateProcessorFactory [collectio

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

tCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348)
 ~[zookeeper-3.6.1.jar:3.6.1]
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) 
[zookeeper-3.6.1.jar:3.6.1]
   [junit4]   2> 1638513 INFO  
(TEST-PeerSyncReplicationTest.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.ZkTestServer Shutting down ZkTestServer.
   [junit4]   2> 1638535 WARN  
(TEST-PeerSyncReplicationTest.test-seed#[9D7405E02D050FA0]-SendThread(127.0.0.1:54019))
 [     ] o.a.z.ClientCnxn An exception was thrown while closing send thread for 
session 0x1006536d1e90000.
   [junit4]   2>           => EndOfStreamException: Unable to read additional 
data from server sessionid 0x1006536d1e90000, likely server has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
   [junit4]   2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable 
to read additional data from server sessionid 0x1006536d1e90000, likely server 
has closed socket
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) 
~[zookeeper-3.6.1.jar:3.6.1]
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348)
 ~[zookeeper-3.6.1.jar:3.6.1]
   [junit4]   2>        at 
org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) 
[zookeeper-3.6.1.jar:3.6.1]
   [junit4]   2> 1638787 INFO  
(TEST-PeerSyncReplicationTest.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:54019
   [junit4]   2> 1638787 INFO  
(TEST-PeerSyncReplicationTest.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:54019
   [junit4]   2> 1638788 INFO  
(TEST-PeerSyncReplicationTest.test-seed#[9D7405E02D050FA0]) [     ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1 54019
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=PeerSyncReplicationTest -Dtests.method=test 
-Dtests.seed=9D7405E02D050FA0 -Dtests.slow=true -Dtests.locale=ro-RO 
-Dtests.timezone=Antarctica/Vostok -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE  139s J1 | PeerSyncReplicationTest.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: There are still nodes 
recoverying - waited for 30 seconds
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([9D7405E02D050FA0:15203A3A83F96258]:0)
   [junit4]    >        at 
org.apache.solr.cloud.AbstractDistribZkTestBase.waitForRecoveriesToFinish(AbstractDistribZkTestBase.java:197)
   [junit4]    >        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForRecoveriesToFinish(AbstractFullDistribZkTestBase.java:1074)
   [junit4]    >        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForThingsToLevelOut(AbstractFullDistribZkTestBase.java:1631)
   [junit4]    >        at 
org.apache.solr.cloud.PeerSyncReplicationTest.bringUpDeadNodeAndEnsureNoReplication(PeerSyncReplicationTest.java:318)
   [junit4]    >        at 
org.apache.solr.cloud.PeerSyncReplicationTest.test(PeerSyncReplicationTest.java:171)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    >        at 
java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    >        at 
java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    >        at 
java.base/java.lang.reflect.Method.invoke(Method.java:567)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:830)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/Users/jenkins/workspace/Lucene-Solr-8.x-MacOSX/solr/build/solr-core/test/J1/temp/solr.cloud.PeerSyncReplicationTest_9D7405E02D050FA0-001
   [junit4]   2> Aug 09, 2020 8:14:18 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene86): 
{rnd_s=PostingsFormat(name=Asserting), 
multiDefault=PostingsFormat(name=Asserting), 
a_t=PostingsFormat(name=LuceneFixedGap), 
_root_=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=Direct), 
text=FST50}, docValues:{other_tl1=DocValuesFormat(name=Lucene80), 
range_facet_l_dv=DocValuesFormat(name=Asserting), 
_version_=DocValuesFormat(name=Lucene80), 
intDefault=DocValuesFormat(name=Lucene80), 
id_i1=DocValuesFormat(name=Lucene80), a_i1=DocValuesFormat(name=Lucene80), 
range_facet_i_dv=DocValuesFormat(name=Lucene80), 
intDvoDefault=DocValuesFormat(name=Direct), 
timestamp=DocValuesFormat(name=Lucene80), 
range_facet_l=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=973, 
maxMBSortInHeap=6.474539927971909, 
sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ro-RO, 
timezone=Antarctica/Vostok
   [junit4]   2> NOTE: Mac OS X 10.14.6 x86_64/AdoptOpenJDK 13.0.2 
(64-bit)/cpus=6,threads=1,free=166177496,total=437256192
   [junit4]   2> NOTE: All tests run in this JVM: [FuzzySearchTest, 
TestFilteredDocIdSet, LeaderVoteWaitTimeoutTest, TestCollationField, 
QueryParsingTest, SolrLogAuditLoggerPluginTest, TestNumericRangeQuery32, 
TestRandomDVFaceting, TestScoreJoinQPNoScore, DocValuesMultiTest, 
SystemInfoHandlerTest, TestSimPolicyCloud, SuggestComponentTest, 
DistributedFacetExistsSmallTest, TestCloudDeleteByQuery, 
TestManagedSchemaThreadSafety, TestCollectionAPI, ZkStateReaderTest, 
TestPushWriter, TestWithCollection, TestReqParamsAPI, 
TestNestedUpdateProcessor, TestSimExtremeIndexing, TestSearchPerf, 
CollectionTooManyReplicasTest, HdfsRecoveryZkTest, 
OverseerCollectionConfigSetProcessorTest, TestCopyFieldCollectionResource, 
TestRemoteStreaming, ExecutePlanActionTest, UUIDFieldTest, 
SpellCheckCollatorWithCollapseTest, TestSolrConfigHandlerConcurrent, 
CopyFieldTest, TestShortCircuitedRequests, TestSolrCoreSnapshots, 
SegmentsInfoRequestHandlerTest, CdcrRequestHandlerTest, DistributedQueueTest, 
ClusterStateMockUtilTest, ZkShardTermsTest, TestMergePolicyConfig, 
TestCustomStream, TestSimClusterStateProvider, NumberUtilsTest, 
TestPolicyCloud, TestInPlaceUpdateWithRouteField, TestDeprecatedFilters, 
MetricsConfigTest, TaggingAttributeTest, VersionInfoTest, OverseerStatusTest, 
RoutingToNodesWithPropertiesTest, TestImplicitCoreProperties, 
IndexSizeTriggerTest, AnalysisErrorHandlingTest, MissingSegmentRecoveryTest, 
TestRTimerTree, TestTrieFacet, HLLUtilTest, CustomCollectionTest, 
TestShardHandlerFactory, AuditLoggerPluginTest, JWTAuthPluginTest, 
PolyFieldTest, PreAnalyzedUpdateProcessorTest, TestCloudRecovery, 
TestSolrQueryParser, DirectSolrConnectionTest, TestSolr4Spatial2, 
TestTolerantSearch, HighlighterTest, CreateRoutedAliasTest, 
TestConfigSetProperties, NodeLostTriggerIntegrationTest, MultiThreadedOCPTest, 
PeerSyncWithLeaderTest, HdfsDirectoryFactoryTest, TestStressReorder, 
TestDistributedMap, TestMacros, DeleteShardTest, TestJsonFacetErrors, 
TestRequestStatusCollectionAPI, IgnoreCommitOptimizeUpdateProcessorFactoryTest, 
BlockJoinFacetSimpleTest, TestQueryUtils, CustomHighlightComponentTest, 
HdfsUnloadDistributedZkTest, TestSchemalessBufferedUpdates, 
TestManagedSynonymGraphFilterFactory, UniqFieldsUpdateProcessorFactoryTest, 
TestTrie, PathHierarchyTokenizerFactoryTest, ProtectedTermFilterFactoryTest, 
TestLuceneMatchVersion, TestReversedWildcardFilterFactory, 
TestWordDelimiterFilterFactory, TokenizerChainTest, TestLuceneIndexBackCompat, 
BasicDistributedZkTest, CollectionStateFormat2Test, CollectionsAPISolrJTest, 
PeerSyncReplicationTest]
   [junit4] Completed [525/917 (3!)] on J1 in 139.64s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 48884 lines...]
[repro] Jenkins log URL: 
https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-MacOSX/1364/consoleText

[repro] Revision: 790ea5ec1074a4adfba94e6cbf1c76b0baa292c7

[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC"
[repro] Repro line:  ant test  -Dtestcase=TestDistributedGrouping 
-Dtests.method=test -Dtests.seed=9D7405E02D050FA0 -Dtests.slow=true 
-Dtests.locale=jv -Dtests.timezone=America/Paramaribo -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

[repro] Repro line:  ant test  -Dtestcase=SoftAutoCommitTest 
-Dtests.method=testSoftAndHardCommitMaxTimeMixedAdds 
-Dtests.seed=9D7405E02D050FA0 -Dtests.slow=true -Dtests.locale=nl-CW 
-Dtests.timezone=NZ-CHAT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1

[repro] Repro line:  ant test  -Dtestcase=PeerSyncReplicationTest 
-Dtests.method=test -Dtests.seed=9D7405E02D050FA0 -Dtests.slow=true 
-Dtests.locale=ro-RO -Dtests.timezone=Antarctica/Vostok -Dtests.asserts=true 
-Dtests.file.encoding=ISO-8859-1

[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean

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

[...truncated 3451 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=15 
-Dtests.class="*.SoftAutoCommitTest|*.PeerSyncReplicationTest|*.TestDistributedGrouping"
 -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseG1GC" 
-Dtests.seed=9D7405E02D050FA0 -Dtests.slow=true -Dtests.locale=nl-CW 
-Dtests.timezone=NZ-CHAT -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1

[...truncated 171 lines...]
[repro] Failures w/original seeds:
[repro]   0/5 failed: org.apache.solr.TestDistributedGrouping
[repro]   0/5 failed: org.apache.solr.cloud.PeerSyncReplicationTest
[repro]   0/5 failed: org.apache.solr.update.SoftAutoCommitTest
[repro] Exiting with code 0

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

Reply via email to