Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20546/
Java: 64bit/jdk-9 -XX:+UseCompressedOops -XX:+UseParallelGC 
--illegal-access=deny

2 tests failed.
FAILED:  org.apache.solr.TestDistributedSearch.test

Error Message:
IOException occured when talking to server at: 
http://127.0.0.1:41703/nh/e/collection1

Stack Trace:
org.apache.solr.client.solrj.SolrServerException: IOException occured when 
talking to server at: http://127.0.0.1:41703/nh/e/collection1
        at 
__randomizedtesting.SeedInfo.seed([A90C2BFAA5D0923:82C4FD6504A164DB]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:641)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:178)
        at 
org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:895)
        at 
org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:858)
        at 
org.apache.solr.client.solrj.SolrClient.deleteByQuery(SolrClient.java:873)
        at 
org.apache.solr.BaseDistributedSearchTestCase.del(BaseDistributedSearchTestCase.java:542)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:1034)
        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:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1019)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
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:844)
Caused by: org.apache.http.NoHttpResponseException: 127.0.0.1:41703 failed to 
respond
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:141)
        at 
org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
        at 
org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
        at 
org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
        at 
org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
        at 
org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
        at 
org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
        at 
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
        at 
org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
        at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
        at 
org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
        at 
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
        at 
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:526)
        ... 48 more


FAILED:  
org.apache.solr.cloud.TestLeaderInitiatedRecoveryThread.testPublishDownState

Error Message:
expected:<36> but was:<37>

Stack Trace:
java.lang.AssertionError: expected:<36> but was:<37>
        at 
__randomizedtesting.SeedInfo.seed([A90C2BFAA5D0923:54ED60410E4040DE]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:472)
        at org.junit.Assert.assertEquals(Assert.java:456)
        at 
org.apache.solr.cloud.TestLeaderInitiatedRecoveryThread.testPublishDownState(TestLeaderInitiatedRecoveryThread.java:170)
        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:564)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
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:844)




Build Log:
[...truncated 12710 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/init-core-data-001
   [junit4]   2> 1332654 WARN  
(SUITE-TestDistributedSearch-seed#[A90C2BFAA5D0923]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 1332655 INFO  
(SUITE-TestDistributedSearch-seed#[A90C2BFAA5D0923]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1332655 INFO  
(SUITE-TestDistributedSearch-seed#[A90C2BFAA5D0923]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: 
@org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-9061";)
   [junit4]   2> 1332655 INFO  
(SUITE-TestDistributedSearch-seed#[A90C2BFAA5D0923]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1332655 INFO  
(SUITE-TestDistributedSearch-seed#[A90C2BFAA5D0923]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /nh/e
   [junit4]   2> 1332725 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1332725 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] o.e.j.s.Server 
jetty-9.3.20.v20170531
   [junit4]   2> 1332726 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@37a9e459{/nh/e,null,AVAILABLE}
   [junit4]   2> 1332727 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@79ec4339{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
   [junit4]   2> 1332727 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] o.e.j.s.Server 
Started @???????ms
   [junit4]   2> 1332727 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/nh/e, 
hostPort=41569, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/control/cores}
   [junit4]   2> 1332727 ERROR 
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1332727 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1332727 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1332727 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1332727 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-25T20:11:24.400784Z
   [junit4]   2> 1332727 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/control/solr.xml
   [junit4]   2> 1332729 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1332754 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf
   [junit4]   2> 1332760 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf
   [junit4]   2> 1332760 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf
   [junit4]   2> 1332761 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/control/cores
   [junit4]   2> 1332761 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1332761 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1332761 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1332775 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1332785 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1332825 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1332829 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1332829 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf
   [junit4]   2> 1332829 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1332829 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1332830 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4257546614247646]
   [junit4]   2> 1332853 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1332853 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1332854 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1332854 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1332854 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1332854 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@660c3ca7[collection1] main]
   [junit4]   2> 1332855 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1332885 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1332954 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1332954 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1332954 INFO  (coreLoadExecutor-4488-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1579543727573041152
   [junit4]   2> 1332955 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1332955 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1332955 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1332955 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1332956 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1332956 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1332956 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1332957 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1332958 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@660c3ca7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1333034 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1333034 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] o.e.j.s.Server 
jetty-9.3.20.v20170531
   [junit4]   2> 1333035 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@6d3cb25e{/nh/e,null,AVAILABLE}
   [junit4]   2> 1333036 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@3c354802{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
   [junit4]   2> 1333036 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] o.e.j.s.Server 
Started @???????ms
   [junit4]   2> 1333036 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/nh/e, 
hostPort=40413, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/shard0/cores}
   [junit4]   2> 1333036 ERROR 
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1333036 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1333036 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1333036 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1333036 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-09-25T20:11:24.709551Z
   [junit4]   2> 1333036 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1333038 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1333053 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf
   [junit4]   2> 1333059 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf
   [junit4]   2> 1333059 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf
   [junit4]   2> 1333060 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/shard0/cores
   [junit4]   2> 1333060 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1333060 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1333061 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1333073 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1333083 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1333125 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1333128 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from 
configset 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1333129 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 
'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@37673cdf
   [junit4]   2> 1333129 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1333129 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.TestDistributedSearch_A90C2BFAA5D0923-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1333129 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=33, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.4257546614247646]
   [junit4]   2> 1333159 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1333159 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH 
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 1333160 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1333160 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1333160 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 1333161 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@76af70ee[collection1] main]
   [junit4]   2> 1333161 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1333177 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1333243 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1333243 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1333243 INFO  (coreLoadExecutor-4499-thread-1) [    ] 
o.a.s.u.UpdateLog Could not find max version in index or recent updates, using 
new clock 1579543727876079616
   [junit4]   2> 1333244 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1333244 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1333244 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1333244 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1333245 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1333245 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1333245 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1333246 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1333247 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@76af70ee[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1333248 INFO  
(TEST-TestDistributedSearch.test-seed#[A90C2BFAA5D0923]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1333252 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1579543727883419648)} 0 1
   [junit4]   2> 1333254 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1579543727886565376)} 0 1
   [junit4]   2> 1333257 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[1 (1579543727888662528)]} 0 2
   [junit4]   2> 1333259 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[1 (1579543727891808256)]} 0 0
   [junit4]   2> 1333260 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[2 (1579543727892856832)]} 0 0
   [junit4]   2> 1333261 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[2 (1579543727893905408)]} 0 0
   [junit4]   2> 1333262 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[3 (1579543727896002560)]} 0 0
   [junit4]   2> 1333263 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[3 (1579543727897051136)]} 0 0
   [junit4]   2> 1333264 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[4 (1579543727897051136)]} 0 0
   [junit4]   2> 1333265 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[4 (1579543727898099712)]} 0 0
   [junit4]   2> 1333266 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[5 (1579543727900196864)]} 0 0
   [junit4]   2> 1333267 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[5 (1579543727900196864)]} 0 0
   [junit4]   2> 1333268 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[6 (1579543727901245440)]} 0 0
   [junit4]   2> 1333269 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[6 (1579543727902294016)]} 0 0
   [junit4]   2> 1333269 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[7 (1579543727903342592)]} 0 0
   [junit4]   2> 1333270 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[7 (1579543727904391168)]} 0 0
   [junit4]   2> 1333271 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[8 (1579543727905439744)]} 0 0
   [junit4]   2> 1333272 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[8 (1579543727906488320)]} 0 0
   [junit4]   2> 1333273 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[9 (1579543727907536896)]} 0 0
   [junit4]   2> 1333274 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[9 (1579543727908585472)]} 0 0
   [junit4]   2> 1333274 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579543727908585472,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1333275 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@36122452 
commitCommandVersion:1579543727908585472
   [junit4]   2> 1333285 INFO  (qtp234515582-13928) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3d224143[collection1] main]
   [junit4]   2> 1333286 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1333288 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3d224143[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))}
   [junit4]   2> 1333288 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 13
   [junit4]   2> 1333289 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579543727924314112,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1333289 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2ba24dc8 
commitCommandVersion:1579543727924314112
   [junit4]   2> 1333292 INFO  (qtp337397764-13945) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3eedd588[collection1] main]
   [junit4]   2> 1333293 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1333295 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3eedd588[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))}
   [junit4]   2> 1333295 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 6
   [junit4]   2> 1333297 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[10 (1579543727931654144)]} 0 1
   [junit4]   2> 1333299 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[10 (1579543727932702720)]} 0 1
   [junit4]   2> 1333300 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[11 (1579543727934799872)]} 0 0
   [junit4]   2> 1333301 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[11 (1579543727935848448)]} 0 0
   [junit4]   2> 1333301 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[12 (1579543727936897024)]} 0 0
   [junit4]   2> 1333302 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[12 (1579543727937945600)]} 0 0
   [junit4]   2> 1333302 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[13 (1579543727937945600)]} 0 0
   [junit4]   2> 1333303 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[13 (1579543727938994176)]} 0 0
   [junit4]   2> 1333304 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[1001 (1579543727938994176)]} 0 0
   [junit4]   2> 1333304 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[1001 (1579543727940042752)]} 0 0
   [junit4]   2> 1333305 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[14 (1579543727941091328)]} 0 0
   [junit4]   2> 1333306 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[14 (1579543727941091328)]} 0 0
   [junit4]   2> 1333306 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[15 (1579543727942139904)]} 0 0
   [junit4]   2> 1333306 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[15 (1579543727942139904)]} 0 0
   [junit4]   2> 1333307 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[16 (1579543727943188480)]} 0 0
   [junit4]   2> 1333307 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[16 (1579543727943188480)]} 0 0
   [junit4]   2> 1333308 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[17 (1579543727944237056)]} 0 0
   [junit4]   2> 1333308 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[17 (1579543727944237056)]} 0 0
   [junit4]   2> 1333309 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[100 (1579543727945285632)]} 0 0
   [junit4]   2> 1333309 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[100 (1579543727945285632)]} 0 0
   [junit4]   2> 1333310 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[101 (1579543727945285633)]} 0 0
   [junit4]   2> 1333310 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[101 (1579543727946334208)]} 0 0
   [junit4]   2> 1333310 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[102 (1579543727946334208)]} 0 0
   [junit4]   2> 1333311 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[102 (1579543727946334209)]} 0 0
   [junit4]   2> 1333311 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[103 (1579543727947382784)]} 0 0
   [junit4]   2> 1333311 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[103 (1579543727947382784)]} 0 0
   [junit4]   2> 1333312 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[104 (1579543727948431360)]} 0 0
   [junit4]   2> 1333312 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[104 (1579543727948431360)]} 0 0
   [junit4]   2> 1333312 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[105 (1579543727948431361)]} 0 0
   [junit4]   2> 1333313 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[105 (1579543727949479936)]} 0 0
   [junit4]   2> 1333313 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[106 (1579543727949479936)]} 0 0
   [junit4]   2> 1333314 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[106 (1579543727949479937)]} 0 0
   [junit4]   2> 1333314 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[107 (1579543727950528512)]} 0 0
   [junit4]   2> 1333314 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[107 (1579543727950528512)]} 0 0
   [junit4]   2> 1333315 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[108 (1579543727950528513)]} 0 0
   [junit4]   2> 1333315 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[108 (1579543727951577088)]} 0 0
   [junit4]   2> 1333315 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[109 (1579543727951577088)]} 0 0
   [junit4]   2> 1333315 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[109 (1579543727951577089)]} 0 0
   [junit4]   2> 1333316 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[110 (1579543727952625664)]} 0 0
   [junit4]   2> 1333316 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[110 (1579543727952625664)]} 0 0
   [junit4]   2> 1333316 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[111 (1579543727952625665)]} 0 0
   [junit4]   2> 1333317 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[111 (1579543727953674240)]} 0 0
   [junit4]   2> 1333317 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[112 (1579543727953674240)]} 0 0
   [junit4]   2> 1333317 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[112 (1579543727953674241)]} 0 0
   [junit4]   2> 1333318 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[113 (1579543727953674241)]} 0 0
   [junit4]   2> 1333318 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[113 (1579543727954722816)]} 0 0
   [junit4]   2> 1333318 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[114 (1579543727954722816)]} 0 0
   [junit4]   2> 1333319 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[114 (1579543727955771392)]} 0 0
   [junit4]   2> 1333319 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[115 (1579543727955771392)]} 0 0
   [junit4]   2> 1333319 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[115 (1579543727955771393)]} 0 0
   [junit4]   2> 1333320 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[116 (1579543727955771393)]} 0 0
   [junit4]   2> 1333320 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[116 (1579543727956819968)]} 0 0
   [junit4]   2> 1333320 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[117 (1579543727956819968)]} 0 0
   [junit4]   2> 1333320 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[117 (1579543727956819969)]} 0 0
   [junit4]   2> 1333321 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[118 (1579543727957868544)]} 0 0
   [junit4]   2> 1333321 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[118 (1579543727957868544)]} 0 0
   [junit4]   2> 1333321 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[119 (1579543727957868545)]} 0 0
   [junit4]   2> 1333322 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[119 (1579543727958917120)]} 0 0
   [junit4]   2> 1333322 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[120 (1579543727958917120)]} 0 0
   [junit4]   2> 1333322 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[120 (1579543727958917121)]} 0 0
   [junit4]   2> 1333323 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[121 (1579543727959965696)]} 0 0
   [junit4]   2> 1333323 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[121 (1579543727959965696)]} 0 0
   [junit4]   2> 1333323 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[122 (1579543727959965697)]} 0 0
   [junit4]   2> 1333324 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[122 (1579543727961014272)]} 0 0
   [junit4]   2> 1333324 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[123 (1579543727961014272)]} 0 0
   [junit4]   2> 1333324 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[123 (1579543727961014273)]} 0 0
   [junit4]   2> 1333325 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[124 (1579543727961014273)]} 0 0
   [junit4]   2> 1333325 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[124 (1579543727962062848)]} 0 0
   [junit4]   2> 1333325 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[125 (1579543727962062848)]} 0 0
   [junit4]   2> 1333325 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[125 (1579543727962062849)]} 0 0
   [junit4]   2> 1333326 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[126 (1579543727963111424)]} 0 0
   [junit4]   2> 1333326 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[126 (1579543727963111424)]} 0 0
   [junit4]   2> 1333326 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[127 (1579543727963111425)]} 0 0
   [junit4]   2> 1333327 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[127 (1579543727963111425)]} 0 0
   [junit4]   2> 1333327 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[128 (1579543727964160000)]} 0 0
   [junit4]   2> 1333327 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[128 (1579543727964160000)]} 0 0
   [junit4]   2> 1333327 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[129 (1579543727964160001)]} 0 0
   [junit4]   2> 1333328 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[129 (1579543727964160001)]} 0 0
   [junit4]   2> 1333328 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[130 (1579543727965208576)]} 0 0
   [junit4]   2> 1333328 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[130 (1579543727965208576)]} 0 0
   [junit4]   2> 1333328 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[131 (1579543727965208577)]} 0 0
   [junit4]   2> 1333329 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[131 (1579543727966257152)]} 0 0
   [junit4]   2> 1333329 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[132 (1579543727966257152)]} 0 0
   [junit4]   2> 1333329 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[132 (1579543727966257153)]} 0 0
   [junit4]   2> 1333329 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[133 (1579543727966257153)]} 0 0
   [junit4]   2> 1333330 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[133 (1579543727967305728)]} 0 0
   [junit4]   2> 1333330 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[134 (1579543727967305728)]} 0 0
   [junit4]   2> 1333330 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[134 (1579543727967305729)]} 0 0
   [junit4]   2> 1333331 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[135 (1579543727967305729)]} 0 0
   [junit4]   2> 1333331 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[135 (1579543727968354304)]} 0 0
   [junit4]   2> 1333331 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[136 (1579543727968354304)]} 0 0
   [junit4]   2> 1333331 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[136 (1579543727968354305)]} 0 0
   [junit4]   2> 1333332 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[137 (1579543727969402880)]} 0 0
   [junit4]   2> 1333332 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[137 (1579543727969402880)]} 0 0
   [junit4]   2> 1333332 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[138 (1579543727969402881)]} 0 0
   [junit4]   2> 1333332 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[138 (1579543727969402881)]} 0 0
   [junit4]   2> 1333333 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[139 (1579543727970451456)]} 0 0
   [junit4]   2> 1333333 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[139 (1579543727970451456)]} 0 0
   [junit4]   2> 1333333 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[140 (1579543727970451457)]} 0 0
   [junit4]   2> 1333334 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[140 (1579543727971500032)]} 0 0
   [junit4]   2> 1333334 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[141 (1579543727971500032)]} 0 0
   [junit4]   2> 1333334 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[141 (1579543727971500033)]} 0 0
   [junit4]   2> 1333335 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[142 (1579543727972548608)]} 0 0
   [junit4]   2> 1333335 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[142 (1579543727972548608)]} 0 0
   [junit4]   2> 1333335 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[143 (1579543727972548609)]} 0 0
   [junit4]   2> 1333336 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[143 (1579543727973597184)]} 0 0
   [junit4]   2> 1333336 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[144 (1579543727973597184)]} 0 0
   [junit4]   2> 1333336 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[144 (1579543727973597185)]} 0 0
   [junit4]   2> 1333336 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[145 (1579543727973597185)]} 0 0
   [junit4]   2> 1333337 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[145 (1579543727974645760)]} 0 0
   [junit4]   2> 1333337 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[146 (1579543727974645760)]} 0 0
   [junit4]   2> 1333337 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[146 (1579543727974645761)]} 0 0
   [junit4]   2> 1333338 INFO  (qtp234515582-13921) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[147 (1579543727974645761)]} 0 0
   [junit4]   2> 1333338 INFO  (qtp337397764-13938) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[147 (1579543727975694336)]} 0 0
   [junit4]   2> 1333338 INFO  (qtp234515582-13926) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[148 (1579543727975694336)]} 0 0
   [junit4]   2> 1333339 INFO  (qtp337397764-13943) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[148 (1579543727975694337)]} 0 0
   [junit4]   2> 1333339 INFO  (qtp234515582-13927) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[149 (1579543727976742912)]} 0 0
   [junit4]   2> 1333339 INFO  (qtp337397764-13944) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={wt=javabin&version=2}{add=[149 (1579543727976742912)]} 0 0
   [junit4]   2> 1333339 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579543727976742913,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1333339 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@36122452 
commitCommandVersion:1579543727976742913
   [junit4]   2> 1333342 INFO  (qtp234515582-13928) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@4a5deb0a[collection1] main]
   [junit4]   2> 1333342 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1333343 INFO  (searcherExecutor-4489-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@4a5deb0a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)
 Uninverting(_1(8.0.0):c59)))}
   [junit4]   2> 1333343 INFO  (qtp234515582-13928) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 3
   [junit4]   2> 1333343 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1579543727980937216,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1333344 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@2ba24dc8 
commitCommandVersion:1579543727980937216
   [junit4]   2> 1333346 INFO  (qtp337397764-13945) [    ] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5932443a[collection1] main]
   [junit4]   2> 1333346 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1333347 INFO  (searcherExecutor-4500-thread-1) [    ] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5932443a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)
 Uninverting(_1(8.0.0):c59)))}
   [junit4]   2> 1333347 INFO  (qtp337397764-13945) [    ] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/nh/e path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 3
   [junit4]   2> 1333348 INFO  (qtp234515582-13921) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1333348 INFO  (qtp234515582-13921) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 1333348 INFO  (qtp234515582-13926) [    ] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1333348 INFO  (qtp234515582-13926) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 1333349 INFO  (qtp234515582-13927) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333357 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=[ff01::114]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&rows=10&version=2&q=*:*&NOW=1506370285022&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333358 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285022&ids=11,1,14,113,15,103,125,6,128,139&isShard=true&shard.url=[ff01::114]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333360 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=[ff01::114]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=10
   [junit4]   2> 1333360 INFO  (qtp234515582-13928) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333362 INFO  (qtp337397764-13939) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285034&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333362 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285034&ids=121,101,145,102,16,104,5,105,138,109&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333365 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1333366 INFO  (qtp234515582-13921) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333367 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285040&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333368 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285040&ids=11,12,134,145,3,16,6,7,118,131&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333369 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1333370 INFO  (qtp234515582-13926) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333377 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&rows=10&version=2&q=*:*&NOW=1506370285044&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333379 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285044&ids=110,112,135,113,136,5,106,9,109,141&isShard=true&shard.url=[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333380 INFO  (qtp337397764-13939) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 1333381 INFO  (qtp234515582-13927) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333382 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285055&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333383 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285055&ids=110,143,133,3,148,137,116,138,129,109&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333385 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1333385 INFO  (qtp234515582-13928) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333387 INFO  (qtp337397764-13939) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285059&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333387 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285059&ids=100,146,113,115,6,117,118,141,142,10&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333389 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1|[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1333390 INFO  (qtp234515582-13921) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333391 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=[ff01::213]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285064&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333392 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285064&ids=143,12,123,135,125,106,129,8,118,119&isShard=true&shard.url=[ff01::213]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333393 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=[ff01::213]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1333393 INFO  (qtp234515582-13926) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333394 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:40413/nh/e/collection1&rows=10&version=2&q=*:*&NOW=1506370285067&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333395 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285067&ids=132,1,134,3,114,126,115,105,128,140&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333395 INFO  (qtp337397764-13939) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1333396 INFO  (qtp234515582-13927) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333403 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::083]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285070&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333404 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285070&ids=110,132,101,134,102,103,4,116,106,9&isShard=true&shard.url=[ff01::083]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333405 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=[ff01::083]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 1333405 INFO  (qtp234515582-13928) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333406 INFO  (qtp337397764-13939) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::083]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&rows=10&version=2&q=*:*&NOW=1506370285079&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333407 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285079&ids=13,124,135,104,137,105,117,140,120,131&isShard=true&shard.url=[ff01::083]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333407 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=[ff01::083]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1333408 INFO  (qtp234515582-13921) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333409 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285082&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333410 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285082&ids=101,134,123,113,3,17,128,8,129,10&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333410 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1333411 INFO  (qtp234515582-13926) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333412 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=http://127.0.0.1:40413/nh/e/collection1&rows=10&version=2&q=*:*&NOW=1506370285084&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333412 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285084&ids=11,12,2,135,15,115,104,148,105,107&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333413 INFO  (qtp337397764-13939) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1333413 INFO  (qtp234515582-13927) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333414 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:40413/nh/e/collection1&rows=10&version=2&q=*:*&NOW=1506370285087&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333415 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285087&ids=132,111,2,123,101,135,15,129,141,10&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333415 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1333416 INFO  (qtp234515582-13928) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333417 INFO  (qtp337397764-13939) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&rows=10&version=2&q=*:*&NOW=1506370285090&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333418 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285090&ids=11,145,14,125,147,104,137,115,127,9&isShard=true&shard.url=[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333419 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1333420 INFO  (qtp234515582-13921) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333421 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e|[ff01::083]:33332/nh/e|[ff01::213]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285093&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333422 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285093&ids=1,146,103,137,126,6,149,117,139,128&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e|[ff01::083]:33332/nh/e|[ff01::213]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333422 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1|[ff01::114]:33332/nh/e|[ff01::083]:33332/nh/e|[ff01::213]:33332/nh/e&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1333423 INFO  (qtp234515582-13926) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333424 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e|[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285096&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333425 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285096&ids=133,101,102,135,125,4,105,116,129,119&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e|[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333426 INFO  (qtp337397764-13939) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e|[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1333426 INFO  (qtp234515582-13927) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333429 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+desc&shard.url=[ff01::114]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e|[ff01::083]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285101&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333430 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285101&ids=143,122,112,2,102,135,146,117,107,130&isShard=true&shard.url=[ff01::114]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e|[ff01::083]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333430 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=[ff01::114]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1|[ff01::213]:33332/nh/e|[ff01::083]:33332/nh/e&sort=n_tdt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1333431 INFO  (qtp234515582-13928) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333432 INFO  (qtp337397764-13939) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tdt1+asc&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e&rows=10&version=2&q=*:*&NOW=1506370285104&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333432 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285104&ids=101,113,3,15,126,6,116,128,109,10&isShard=true&shard.url=http://127.0.0.1:40413/nh/e/collection1|[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333433 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=http://127.0.0.1:40413/nh/e/collection1|[ff01::083]:33332/nh/e|[ff01::114]:33332/nh/e&sort=n_tdt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1333434 INFO  (qtp234515582-13921) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort=a_i_ni_p+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1333435 INFO  (qtp337397764-13938) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=a_i_ni_p+desc&shard.url=[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&rows=10&version=2&q=*:*&NOW=1506370285107&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333435 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1506370285107&ids=1,12,2,13,3,5,7,8,9,10&isShard=true&shard.url=[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1333436 INFO  (qtp337397764-13944) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&shards=[ff01::114]:33332/nh/e|[ff01::213]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&sort=a_i_ni_p+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1333437 INFO  (qtp234515582-13926) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={q=*:*&distrib=false&sort={!func}testfunc(add(a_i_ni_p,5))+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333438 INFO  (qtp337397764-13945) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort={!func}testfunc(add(a_i_ni_p,5))+desc&shard.url=[ff01::083]:33332/nh/e|http://127.0.0.1:40413/nh/e/collection1&rows=10&version=2&q=*:*&NOW=1506370285110&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1333439 INFO  (qtp337397764-13943) [    ] o.a.s.c.S.Request 
[collection1]  webapp=/nh/e path=/select params={q=*

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

ic reporters for registry=solr.collection.collection1.shard1.leader, 
tag=175233832
   [junit4]   2> 2172407 INFO  
(TEST-TestLeaderInitiatedRecoveryThread.testPublishDownState-seed#[A90C2BFAA5D0923])
 [    ] o.e.j.s.AbstractConnector Stopped 
ServerConnector@422c550e{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
   [junit4]   2> 2172407 INFO  
(TEST-TestLeaderInitiatedRecoveryThread.testPublishDownState-seed#[A90C2BFAA5D0923])
 [    ] o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@7346012a{/,null,UNAVAILABLE}
   [junit4]   2> 2172408 ERROR 
(TEST-TestLeaderInitiatedRecoveryThread.testPublishDownState-seed#[A90C2BFAA5D0923])
 [    ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so 
ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 2172408 INFO  
(TEST-TestLeaderInitiatedRecoveryThread.testPublishDownState-seed#[A90C2BFAA5D0923])
 [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37775 37775
   [junit4]   2> 2187422 INFO  (Thread-6245) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:37775 37775
   [junit4]   2> 2187423 WARN  (Thread-6245) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/aliases.json
   [junit4]   2>        4       /solr/security.json
   [junit4]   2>        4       /solr/configs/conf1
   [junit4]   2>        3       /solr/collections/collection1/state.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/clusterstate.json
   [junit4]   2>        6       /solr/clusterprops.json
   [junit4]   2>        2       
/solr/collections/collection1/leader_elect/shard1/election/98721537207042057-core_node22-n_0000000000
   [junit4]   2>        2       
/solr/overseer_elect/election/98721537207042057-127.0.0.1:42249_-n_0000000001
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        6       /solr/collections
   [junit4]   2>        5       /solr/live_nodes
   [junit4]   2>        3       /solr/overseer/queue
   [junit4]   2>        3       /solr/overseer/collection-queue-work
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=TestLeaderInitiatedRecoveryThread 
-Dtests.method=testPublishDownState -Dtests.seed=A90C2BFAA5D0923 
-Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ar-SY 
-Dtests.timezone=America/Dawson_Creek -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] FAILURE 26.9s J2 | 
TestLeaderInitiatedRecoveryThread.testPublishDownState <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<36> but 
was:<37>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([A90C2BFAA5D0923:54ED60410E4040DE]:0)
   [junit4]    >        at 
org.apache.solr.cloud.TestLeaderInitiatedRecoveryThread.testPublishDownState(TestLeaderInitiatedRecoveryThread.java:170)
   [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:564)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
   [junit4]    >        at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestLeaderInitiatedRecoveryThread_A90C2BFAA5D0923-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=1443, maxMBSortInHeap=5.429571290879489, 
sim=RandomSimilarity(queryNorm=true): {}, locale=ar-SY, 
timezone=America/Dawson_Creek
   [junit4]   2> NOTE: Linux 4.10.0-33-generic amd64/Oracle Corporation 9 
(64-bit)/cpus=8,threads=1,free=73423376,total=520617984
   [junit4]   2> NOTE: All tests run in this JVM: 
[TestSchemalessBufferedUpdates, SuggesterFSTTest, TestCoreAdminApis, 
DistributedVersionInfoTest, TestInPlaceUpdatesDistrib, BitVectorTest, 
QueryEqualityTest, ShowFileRequestHandlerTest, FacetPivotSmallTest, 
TestDownShardTolerantSearch, TestHalfAndHalfDocValues, 
TestReversedWildcardFilterFactory, HdfsDirectoryFactoryTest, 
TermVectorComponentDistributedTest, TestMaxTokenLenTokenizer, 
TestJsonFacetRefinement, TestMaxScoreQueryParser, 
TestManagedSchemaThreadSafety, AnalyticsMergeStrategyTest, 
TestAuthorizationFramework, RuleEngineTest, DateMathParserTest, 
StatsComponentTest, XsltUpdateRequestHandlerTest, TestClusterProperties, 
MultiThreadedOCPTest, LargeFieldTest, TestEmbeddedSolrServerSchemaAPI, 
NotRequiredUniqueKeyTest, PathHierarchyTokenizerFactoryTest, 
CheckHdfsIndexTest, TestSolrCoreProperties, TestLocalFSCloudBackupRestore, 
TestFieldSortValues, TestReqParamsAPI, PrimitiveFieldTypeTest, TestSolrXml, 
TestZkChroot, TestLeaderElectionWithEmptyReplica, RollingRestartTest, 
TestJavabinTupleStreamParser, CircularListTest, 
HdfsWriteToMultipleCollectionsTest, ResponseHeaderTest, 
TriLevelCompositeIdRoutingTest, CdcrRequestHandlerTest, OverseerTest, 
TestOrdValues, BadIndexSchemaTest, HdfsCollectionsAPIDistributedZkTest, 
TestSurroundQueryParser, TestDFRSimilarityFactory, CdcrBootstrapTest, 
TestSolrCloudWithHadoopAuthPlugin, TestLFUCache, TermsComponentTest, 
CoreSorterTest, TestSort, TestConfigReload, SpellPossibilityIteratorTest, 
LeaderInitiatedRecoveryOnShardRestartTest, InfoHandlerTest, 
PeerSyncReplicationTest, TestSolrDeletionPolicy2, URLClassifyProcessorTest, 
TestSolrCloudWithKerberosAlt, SignatureUpdateProcessorFactoryTest, 
TestSlowCompositeReaderWrapper, UnloadDistributedZkTest, HLLSerializationTest, 
SimplePostToolTest, TimeZoneUtilsTest, TestNumericTerms32, 
TestManagedStopFilterFactory, TestCloudManagedSchema, 
TestPayloadScoreQParserPlugin, CurrencyFieldTypeTest, 
TestFieldCacheVsDocValues, FastVectorHighlighterTest, 
TolerantUpdateProcessorTest, TestSolrCLIRunExample, TestStressInPlaceUpdates, 
TestHttpShardHandlerFactory, SearchHandlerTest, TestIndexSearcher, 
BlockDirectoryTest, AssignTest, TestNoOpRegenerator, 
DeleteLastCustomShardedReplicaTest, ClassificationUpdateProcessorTest, 
JavabinLoaderTest, TestTolerantUpdateProcessorCloud, TestDynamicLoading, 
TestRequestStatusCollectionAPI, TestTrie, AtomicUpdatesTest, 
PreAnalyzedFieldTest, ShufflingReplicaListTransformerTest, 
SolrCoreMetricManagerTest, TestExactSharedStatsCache, AutoCommitTest, 
TestFreeTextSuggestions, AutoScalingHandlerTest, SuggestComponentTest, 
TestExportWriter, TestApiFramework, DistributedFacetExistsSmallTest, 
CoreAdminCreateDiscoverTest, TestCryptoKeys, TestSubQueryTransformerDistrib, 
HdfsLockFactoryTest, SolrMetricsIntegrationTest, SolrXmlInZkTest, 
TestFastWriter, SolrJmxReporterCloudTest, SyncSliceTest, BasicZkTest, 
RecoveryZkTest, FullSolrCloudDistribCmdsTest, ShardRoutingCustomTest, 
TestDistributedSearch, TestDistributedGrouping, TestRecovery, ZkControllerTest, 
DistributedTermsComponentTest, SimpleFacetsTest, TestSolr4Spatial, 
SpellCheckComponentTest, TestGroupingSearch, TestFiltering, TestFunctionQuery, 
DirectUpdateHandlerTest, TestBadConfig, HighlighterTest, TestCoreDiscovery, 
TestExtendedDismaxParser, SolrRequestParserTest, TestFoldingMultitermQuery, 
DocValuesTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, 
SpatialFilterTest, PolyFieldTest, NoCacheHeaderTest, 
WordBreakSolrSpellCheckerTest, SolrCoreCheckLockOnStartupTest, 
TestPseudoReturnFields, TestWordDelimiterFilterFactory, 
DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, 
SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, 
DistanceFunctionTest, TestSolrDeletionPolicy1, SolrInfoBeanTest, 
CacheHeaderTest, TestQueryUtils, TestWriterPerf, TestOmitPositions, 
RequestHandlersTest, DocumentBuilderTest, TermVectorComponentTest, 
RequiredFieldsTest, TestSolrQueryParser, RegexBoostProcessorTest, 
TestJmxIntegration, TestCollationField, ReturnFieldsTest, 
UpdateRequestProcessorFactoryTest, QueryParsingTest, TestAnalyzedSuggestions, 
UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, PingRequestHandlerTest, 
CSVRequestHandlerTest, HighlighterConfigTest, SOLR749Test, 
TestQuerySenderListener, AlternateDirectoryTest, TestSolrIndexConfig, 
CopyFieldTest, ResponseLogComponentTest, BadComponentTest, 
TestMergePolicyConfig, MultiTermTest, TestDocSet, TestSearchPerf, 
NumericFieldsTest, MinimalSchemaTest, TestConfig, OutputWriterTest, 
TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, 
DirectSolrConnectionTest, TestCodecSupport, EchoParamsTest, 
TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, 
TestIBSimilarityFactory, ResourceLoaderTest, TestFastOutputStream, 
PluginInfoTest, ChaosMonkeyNothingIsSafeTest, BaseCdcrDistributedZkTest, 
CdcrReplicationDistributedZkTest, CdcrVersionReplicationTest, 
ChaosMonkeySafeLeaderWithPullReplicasTest, CloudExitableDirectoryReaderTest, 
CollectionStateFormat2Test, CollectionTooManyReplicasTest, 
CollectionsAPISolrJTest, DeleteNodeTest, DeleteShardTest, 
DistribDocExpirationUpdateProcessorTest, DocValuesNotIndexedTest, 
HealthCheckHandlerTest, LeaderElectionContextKeyTest, 
LeaderFailoverAfterPartitionTest, MissingSegmentRecoveryTest, 
MoveReplicaHDFSTest, OverriddenZkACLAndCredentialsProvidersTest, 
ReplaceNodeTest, SharedFSAutoReplicaFailoverTest, 
SimpleCollectionCreateDeleteTest, SolrCloudExampleTest, TestCloudDeleteByQuery, 
TestCloudPseudoReturnFields, TestCloudRecovery, TestConfigSetsAPIZkFailure, 
TestDistribDocBasedVersion, TestHdfsCloudBackupRestore, 
TestLeaderElectionZkExpiry, TestLeaderInitiatedRecoveryThread]
   [junit4] Completed [549/733 (2!)] on J2 in 26.97s, 1 test, 1 failure <<< 
FAILURES!

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

Reply via email to