[JENKINS] Lucene-Solr-Tests-master - Build # 2322 - Still Unstable

[Apache Jenkins Server]

Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2322/

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

Error Message:
Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle 
this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: 
Time allowed to handle this request exceeded  at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
  at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:273)
  at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
  at java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)  at 
java.util.concurrent.FutureTask.run(FutureTask.java:266)  at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
  at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
  at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) 
 at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) 
 at java.lang.Thread.run(Thread.java:748) ,time=48}

Stack Trace:
java.lang.AssertionError: Expected to find shardAddress in the up shard info: 
{error=org.apache.solr.client.solrj.SolrServerException: Time allowed to handle 
this request exceeded,trace=org.apache.solr.client.solrj.SolrServerException: 
Time allowed to handle this request exceeded
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:460)
        at 
org.apache.solr.handler.component.HttpShardHandlerFactory.makeLoadBalancedRequest(HttpShardHandlerFactory.java:273)
        at 
org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:175)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at 
com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
        at 
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
        at 
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
        at java.lang.Thread.run(Thread.java:748)
,time=48}
        at 
__randomizedtesting.SeedInfo.seed([3BA892261A2F1034:B3FCADFCB4D37DCC]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.TestDistributedSearch.comparePartialResponses(TestDistributedSearch.java:1191)
        at 
org.apache.solr.TestDistributedSearch.queryPartialResults(TestDistributedSearch.java:1132)
        at 
org.apache.solr.TestDistributedSearch.test(TestDistributedSearch.java:992)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java: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 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)


FAILED:  org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testSearchRate

Error Message:
The trigger did not fire at all

Stack Trace:
java.lang.AssertionError: The trigger did not fire at all
        at 
__randomizedtesting.SeedInfo.seed([3BA892261A2F1034:66E08CAFD5E9B67B]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.assertTrue(Assert.java:43)
        at 
org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testSearchRate(TestLargeCluster.java:547)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at 
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
        at 
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
        at 
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
        at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 12522 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/init-core-data-001
   [junit4]   2> 1194720 WARN  
(SUITE-TestDistributedSearch-seed#[3BA892261A2F1034]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=6 numCloses=6
   [junit4]   2> 1194720 INFO  
(SUITE-TestDistributedSearch-seed#[3BA892261A2F1034]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 1194721 INFO  
(SUITE-TestDistributedSearch-seed#[3BA892261A2F1034]-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> 1194721 INFO  
(SUITE-TestDistributedSearch-seed#[3BA892261A2F1034]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1194721 INFO  
(SUITE-TestDistributedSearch-seed#[3BA892261A2F1034]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 1194837 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1194839 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T18:27:37-03:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1194860 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1194860 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1194860 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1194860 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@463fb338{/,null,AVAILABLE}
   [junit4]   2> 1194863 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@2d43fc8a{HTTP/1.1,[http/1.1]}{127.0.0.1:44667}
   [junit4]   2> 1194863 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] o.e.j.s.Server 
Started @1197196ms
   [junit4]   2> 1194863 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=44667, 
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/control/cores}
   [junit4]   2> 1194863 ERROR 
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1194863 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1194864 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1194864 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1194864 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-13T21:45:33.479Z
   [junit4]   2> 1194865 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/control/solr.xml
   [junit4]   2> 1194868 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1194868 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1194869 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1194913 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29
   [junit4]   2> 1195793 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29
   [junit4]   2> 1195793 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29
   [junit4]   2> 1195795 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/control/cores
   [junit4]   2> 1195795 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1195795 INFO  (coreLoadExecutor-1606-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1195821 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1195853 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1195871 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1195948 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1195958 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1195958 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29
   [junit4]   2> 1195958 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1195958 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1195960 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1196005 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1196005 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1196006 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1196006 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1196007 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=43.9814453125, 
floorSegmentMB=1.298828125, forceMergeDeletesPctAllowed=2.9013508206496272, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1196007 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@6d754d9c[collection1] main]
   [junit4]   2> 1196008 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1196021 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1196068 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1196069 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1196069 INFO  (coreLoadExecutor-1606-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1592323824349609984
   [junit4]   2> 1196069 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1196069 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1196069 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1196069 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1196070 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1196070 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1196070 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1196070 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1196071 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@6d754d9c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1196182 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1196183 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] o.e.j.s.Server 
jetty-9.4.8.v20171121, build timestamp: 2017-11-21T18:27:37-03:00, git hash: 
82b8fb23f757335bb3329d540ce37a2a2615f0a8
   [junit4]   2> 1196184 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1196184 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1196184 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.session Scavenging every 600000ms
   [junit4]   2> 1196185 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@75655bac{/,null,AVAILABLE}
   [junit4]   2> 1196185 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@75690994{HTTP/1.1,[http/1.1]}{127.0.0.1:36716}
   [junit4]   2> 1196185 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] o.e.j.s.Server 
Started @1198519ms
   [junit4]   2> 1196185 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=36716, 
coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/shard0/cores}
   [junit4]   2> 1196186 ERROR 
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1196186 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
8.0.0
   [junit4]   2> 1196186 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1196186 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 1196186 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-02-13T21:45:34.801Z
   [junit4]   2> 1196186 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1196190 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 1196190 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 1196191 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1196222 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29
   [junit4]   2> 1196230 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29
   [junit4]   2> 1196231 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29
   [junit4]   2> 1196232 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/shard0/cores
   [junit4]   2> 1196232 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1196233 INFO  (coreLoadExecutor-1617-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1196234 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1196260 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 1196288 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1196370 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1196377 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1196378 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 
'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: 
com.sun.jmx.mbeanserver.JmxMBeanServer@47e4c29
   [junit4]   2> 1196378 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1196378 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_3BA892261A2F1034-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1196379 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: 
minMergeSize=1677721, mergeFactor=23, maxMergeSize=2147483648, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.0]
   [junit4]   2> 1196428 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1196428 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1196429 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1196429 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1196430 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=27, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=43.9814453125, 
floorSegmentMB=1.298828125, forceMergeDeletesPctAllowed=2.9013508206496272, 
segmentsPerTier=41.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1196430 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5dd4375a[collection1] main]
   [junit4]   2> 1196431 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1196441 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1196489 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 1196489 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1196489 INFO  (coreLoadExecutor-1617-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1592323824790011904
   [junit4]   2> 1196491 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1196491 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1196491 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1196491 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1196492 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1196492 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1196492 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1196492 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1196493 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5dd4375a[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1196992 INFO  
(TEST-TestDistributedSearch.test-seed#[3BA892261A2F1034]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1196998 INFO  (qtp805416817-5865) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1592323825320591360)} 0 3
   [junit4]   2> 1197005 INFO  (qtp523753748-5882) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1592323825325834240)} 0 5
   [junit4]   2> 1197013 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1592323825332125696)]} 0 7
   [junit4]   2> 1197016 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1592323825340514304)]} 0 2
   [junit4]   2> 1197019 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1592323825343660032)]} 0 1
   [junit4]   2> 1197020 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1592323825345757184)]} 0 1
   [junit4]   2> 1197021 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1592323825347854336)]} 0 0
   [junit4]   2> 1197022 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1592323825348902912)]} 0 0
   [junit4]   2> 1197023 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1592323825349951488)]} 0 0
   [junit4]   2> 1197024 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1592323825351000064)]} 0 0
   [junit4]   2> 1197025 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1592323825352048640)]} 0 0
   [junit4]   2> 1197026 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1592323825353097216)]} 0 0
   [junit4]   2> 1197027 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1592323825354145792)]} 0 0
   [junit4]   2> 1197028 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1592323825355194368)]} 0 0
   [junit4]   2> 1197029 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1592323825355194368)]} 0 0
   [junit4]   2> 1197030 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1592323825356242944)]} 0 0
   [junit4]   2> 1197031 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1592323825357291520)]} 0 1
   [junit4]   2> 1197032 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1592323825359388672)]} 0 0
   [junit4]   2> 1197033 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1592323825360437248)]} 0 0
   [junit4]   2> 1197034 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1592323825361485824)]} 0 0
   [junit4]   2> 1197035 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592323825362534400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1197035 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@47f4f0cb 
commitCommandVersion:1592323825362534400
   [junit4]   2> 1197049 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@5c21dfbe[collection1] main]
   [junit4]   2> 1197050 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1197052 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5c21dfbe[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))}
   [junit4]   2> 1197052 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 17
   [junit4]   2> 1197052 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592323825380360192,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1197052 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@49878971 
commitCommandVersion:1592323825380360192
   [junit4]   2> 1197061 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@fc28095[collection1] main]
   [junit4]   2> 1197062 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1197066 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@fc28095[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)))}
   [junit4]   2> 1197066 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 13
   [junit4]   2> 1197068 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1592323825395040256)]} 0 1
   [junit4]   2> 1197070 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1592323825398185984)]} 0 1
   [junit4]   2> 1197078 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1592323825406574592)]} 0 1
   [junit4]   2> 1197080 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1592323825408671744)]} 0 1
   [junit4]   2> 1197092 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1592323825410768896)]} 0 10
   [junit4]   2> 1197099 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1592323825429643264)]} 0 0
   [junit4]   2> 1197101 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1592323825431740416)]} 0 0
   [junit4]   2> 1197103 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1592323825432788992)]} 0 0
   [junit4]   2> 1197104 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1592323825433837568)]} 0 0
   [junit4]   2> 1197105 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1592323825435934720)]} 0 0
   [junit4]   2> 1197106 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1592323825436983296)]} 0 0
   [junit4]   2> 1197107 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1592323825438031872)]} 0 0
   [junit4]   2> 1197108 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1592323825438031872)]} 0 0
   [junit4]   2> 1197109 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1592323825439080448)]} 0 0
   [junit4]   2> 1197109 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1592323825440129024)]} 0 0
   [junit4]   2> 1197110 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1592323825441177600)]} 0 0
   [junit4]   2> 1197112 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1592323825442226176)]} 0 0
   [junit4]   2> 1197113 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1592323825443274752)]} 0 0
   [junit4]   2> 1197114 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1592323825444323328)]} 0 0
   [junit4]   2> 1197114 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1592323825445371904)]} 0 0
   [junit4]   2> 1197115 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1592323825446420480)]} 0 0
   [junit4]   2> 1197116 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1592323825447469056)]} 0 0
   [junit4]   2> 1197117 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1592323825447469056)]} 0 0
   [junit4]   2> 1197119 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1592323825449566208)]} 0 1
   [junit4]   2> 1197120 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1592323825450614784)]} 0 0
   [junit4]   2> 1197120 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1592323825451663360)]} 0 0
   [junit4]   2> 1197121 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1592323825452711936)]} 0 0
   [junit4]   2> 1197122 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1592323825453760512)]} 0 0
   [junit4]   2> 1197123 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1592323825453760512)]} 0 0
   [junit4]   2> 1197124 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1592323825454809088)]} 0 0
   [junit4]   2> 1197124 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1592323825455857664)]} 0 0
   [junit4]   2> 1197125 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1592323825456906240)]} 0 0
   [junit4]   2> 1197126 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1592323825457954816)]} 0 0
   [junit4]   2> 1197127 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1592323825457954816)]} 0 0
   [junit4]   2> 1197128 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1592323825459003392)]} 0 0
   [junit4]   2> 1197128 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1592323825460051968)]} 0 0
   [junit4]   2> 1197129 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1592323825461100544)]} 0 0
   [junit4]   2> 1197130 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1592323825462149120)]} 0 0
   [junit4]   2> 1197131 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1592323825462149120)]} 0 0
   [junit4]   2> 1197132 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1592323825463197696)]} 0 0
   [junit4]   2> 1197133 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1592323825464246272)]} 0 0
   [junit4]   2> 1197133 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1592323825465294848)]} 0 0
   [junit4]   2> 1197134 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1592323825466343424)]} 0 0
   [junit4]   2> 1197135 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1592323825467392000)]} 0 0
   [junit4]   2> 1197136 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1592323825467392000)]} 0 0
   [junit4]   2> 1197136 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1592323825468440576)]} 0 0
   [junit4]   2> 1197137 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1592323825469489152)]} 0 0
   [junit4]   2> 1197138 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1592323825470537728)]} 0 0
   [junit4]   2> 1197139 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1592323825470537728)]} 0 0
   [junit4]   2> 1197139 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1592323825471586304)]} 0 0
   [junit4]   2> 1197140 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1592323825472634880)]} 0 0
   [junit4]   2> 1197141 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1592323825473683456)]} 0 0
   [junit4]   2> 1197142 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1592323825473683456)]} 0 0
   [junit4]   2> 1197142 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1592323825474732032)]} 0 0
   [junit4]   2> 1197143 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1592323825475780608)]} 0 0
   [junit4]   2> 1197144 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1592323825476829184)]} 0 0
   [junit4]   2> 1197145 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1592323825476829184)]} 0 0
   [junit4]   2> 1197145 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1592323825477877760)]} 0 0
   [junit4]   2> 1197146 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1592323825478926336)]} 0 0
   [junit4]   2> 1197147 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1592323825479974912)]} 0 0
   [junit4]   2> 1197148 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1592323825479974912)]} 0 0
   [junit4]   2> 1197148 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1592323825481023488)]} 0 0
   [junit4]   2> 1197149 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1592323825482072064)]} 0 0
   [junit4]   2> 1197150 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1592323825483120640)]} 0 0
   [junit4]   2> 1197151 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1592323825483120640)]} 0 0
   [junit4]   2> 1197151 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1592323825484169216)]} 0 0
   [junit4]   2> 1197152 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1592323825485217792)]} 0 0
   [junit4]   2> 1197153 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1592323825486266368)]} 0 0
   [junit4]   2> 1197154 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1592323825486266368)]} 0 0
   [junit4]   2> 1197154 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1592323825487314944)]} 0 0
   [junit4]   2> 1197155 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1592323825488363520)]} 0 0
   [junit4]   2> 1197156 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1592323825489412096)]} 0 0
   [junit4]   2> 1197157 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1592323825489412096)]} 0 0
   [junit4]   2> 1197157 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1592323825490460672)]} 0 0
   [junit4]   2> 1197158 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1592323825491509248)]} 0 0
   [junit4]   2> 1197159 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1592323825492557824)]} 0 0
   [junit4]   2> 1197160 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1592323825492557824)]} 0 0
   [junit4]   2> 1197160 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1592323825493606400)]} 0 0
   [junit4]   2> 1197161 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1592323825494654976)]} 0 0
   [junit4]   2> 1197163 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1592323825496752128)]} 0 0
   [junit4]   2> 1197164 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1592323825496752128)]} 0 0
   [junit4]   2> 1197166 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1592323825497800704)]} 0 1
   [junit4]   2> 1197166 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1592323825499897856)]} 0 0
   [junit4]   2> 1197167 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1592323825500946432)]} 0 0
   [junit4]   2> 1197168 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1592323825501995008)]} 0 0
   [junit4]   2> 1197177 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1592323825510383616)]} 0 0
   [junit4]   2> 1197177 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1592323825511432192)]} 0 0
   [junit4]   2> 1197178 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1592323825512480768)]} 0 0
   [junit4]   2> 1197179 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1592323825513529344)]} 0 0
   [junit4]   2> 1197180 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1592323825513529344)]} 0 0
   [junit4]   2> 1197181 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1592323825514577920)]} 0 0
   [junit4]   2> 1197181 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1592323825515626496)]} 0 0
   [junit4]   2> 1197182 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1592323825516675072)]} 0 0
   [junit4]   2> 1197183 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1592323825516675072)]} 0 0
   [junit4]   2> 1197183 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1592323825517723648)]} 0 0
   [junit4]   2> 1197184 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1592323825518772224)]} 0 0
   [junit4]   2> 1197185 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1592323825519820800)]} 0 0
   [junit4]   2> 1197186 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1592323825519820800)]} 0 0
   [junit4]   2> 1197186 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1592323825520869376)]} 0 0
   [junit4]   2> 1197187 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1592323825521917952)]} 0 0
   [junit4]   2> 1197188 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1592323825522966528)]} 0 0
   [junit4]   2> 1197189 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1592323825522966528)]} 0 0
   [junit4]   2> 1197189 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1592323825524015104)]} 0 0
   [junit4]   2> 1197190 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1592323825525063680)]} 0 0
   [junit4]   2> 1197191 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1592323825526112256)]} 0 0
   [junit4]   2> 1197192 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1592323825526112256)]} 0 0
   [junit4]   2> 1197192 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1592323825527160832)]} 0 0
   [junit4]   2> 1197193 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1592323825528209408)]} 0 0
   [junit4]   2> 1197194 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1592323825528209408)]} 0 0
   [junit4]   2> 1197194 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1592323825529257984)]} 0 0
   [junit4]   2> 1197195 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1592323825530306560)]} 0 0
   [junit4]   2> 1197196 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1592323825531355136)]} 0 0
   [junit4]   2> 1197197 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1592323825531355136)]} 0 0
   [junit4]   2> 1197197 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1592323825532403712)]} 0 0
   [junit4]   2> 1197198 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1592323825533452288)]} 0 0
   [junit4]   2> 1197199 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1592323825533452288)]} 0 0
   [junit4]   2> 1197199 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1592323825534500864)]} 0 0
   [junit4]   2> 1197200 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1592323825535549440)]} 0 0
   [junit4]   2> 1197201 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592323825536598016,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1197201 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@47f4f0cb 
commitCommandVersion:1592323825536598016
   [junit4]   2> 1197214 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@1fd5cbf3[collection1] main]
   [junit4]   2> 1197216 INFO  
(searcherExecutor-1607-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@1fd5cbf3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)
 Uninverting(_1(8.0.0):c59)))}
   [junit4]   2> 1197216 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1197216 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 15
   [junit4]   2> 1197217 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1592323825553375232,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1197217 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@49878971 
commitCommandVersion:1592323825553375232
   [junit4]   2> 1197229 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@419e90a7[collection1] main]
   [junit4]   2> 1197231 INFO  
(searcherExecutor-1618-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@419e90a7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):c9)
 Uninverting(_1(8.0.0):c59)))}
   [junit4]   2> 1197231 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1197231 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 14
   [junit4]   2> 1197233 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1197233 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 1197234 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1197234 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 1197235 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197246 INFO  (qtp523753748-5887) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+desc&shard.url=http://127.0.0.1:36716//collection1&rows=10&version=2&q=*:*&NOW=1518558335852&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197266 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558335852&ids=143,11,101,13,124,113,127,107,109,131&isShard=true&shard.url=http://127.0.0.1:36716//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197272 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:36716//collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=35
   [junit4]   2> 1197273 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197897 INFO  (qtp523753748-5885) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:36716//collection1&rows=10&version=2&q=*:*&NOW=1518558335890&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197901 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558335890&ids=134,145,125,16,5,137,138,17,118,9&isShard=true&shard.url=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:36716//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197917 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/|http://127.0.0.1:36716//collection1&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=641
   [junit4]   2> 1197919 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197922 INFO  (qtp523753748-5885) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=[ff01::083]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1518558336536&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197923 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336536&ids=11,133,145,3,147,15,116,128,8,107&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197937 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=15
   [junit4]   2> 1197938 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197940 INFO  (qtp523753748-5885) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+asc&shard.url=http://127.0.0.1:36716//collection1|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1518558336554&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197942 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336554&ids=100,123,135,14,4,103,104,138,1001,117&isShard=true&shard.url=http://127.0.0.1:36716//collection1|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197952 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:36716//collection1|[ff01::213]:33332/|[ff01::114]:33332/|[ff01::083]:33332/&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=12
   [junit4]   2> 1197953 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197954 INFO  (qtp523753748-5885) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:36716//collection1&rows=10&version=2&q=*:*&NOW=1518558336569&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197955 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336569&ids=134,124,146,135,125,127,139,107,118,131&isShard=true&shard.url=http://127.0.0.1:36716//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197963 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:36716//collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 1197964 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197966 INFO  (qtp523753748-5885) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::083]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1518558336580&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197967 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336580&ids=121,11,110,111,123,3,103,104,17,116&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197969 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1197969 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197971 INFO  (qtp523753748-5885) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:36716//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1518558336585&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197972 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336585&ids=113,114,147,117,8,107,9,140,130,10&isShard=true&shard.url=http://127.0.0.1:36716//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197973 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:36716//collection1|[ff01::114]:33332/&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1197974 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197976 INFO  (qtp523753748-5885) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+asc&shard.url=http://127.0.0.1:36716//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1518558336590&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197977 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336590&ids=11,122,144,12,101,5,104,16,141,131&isShard=true&shard.url=http://127.0.0.1:36716//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197978 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:36716//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1197978 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197980 INFO  (qtp523753748-5885) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:36716//collection1|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1518558336594&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197981 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336594&ids=1,146,135,4,147,104,115,16,117,10&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:36716//collection1|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197982 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:36716//collection1|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1197983 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197985 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1518558336599&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197986 INFO  (qtp523753748-5884) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336599&ids=121,132,100,3,15,136,126,105,141,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197987 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1197988 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197989 INFO  (qtp523753748-5885) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::213]:33332/|http://127.0.0.1:36716//collection1|[ff01::083]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1518558336604&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197990 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336604&ids=101,124,103,136,125,5,117,109,120,10&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:36716//collection1|[ff01::083]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197991 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:36716//collection1|[ff01::083]:33332/|[ff01::114]:33332/&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1197992 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197993 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+asc&shard.url=[ff01::114]:33332/|http://127.0.0.1:36716//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1518558336608&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197994 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336608&ids=110,100,13,3,113,14,114,6,139,131&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:36716//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197995 INFO  (qtp523753748-5887) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:36716//collection1|[ff01::083]:33332/&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1197996 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1197997 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:36716//collection1&rows=10&version=2&q=*:*&NOW=1518558336611&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1197998 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336611&ids=1,144,134,146,147,103,136,5,139,8&isShard=true&shard.url=http://127.0.0.1:36716//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1197999 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:36716//collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1197999 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1198001 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+asc&shard.url=http://127.0.0.1:36716//collection1|[ff01::083]:33332/&rows=10&version=2&q=*:*&NOW=1518558336615&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1198002 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336615&ids=110,132,122,112,4,148,16,107,9,142&isShard=true&shard.url=http://127.0.0.1:36716//collection1|[ff01::083]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1198003 INFO  (qtp523753748-5887) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:36716//collection1|[ff01::083]:33332/&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1198004 INFO  (qtp805416817-5869) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1198005 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::114]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1518558336620&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1198006 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336620&ids=144,100,1,113,136,114,117,7,107,141&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1198007 INFO  (qtp523753748-5886) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:36716//collection1|[ff01::213]:33332/&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1198008 INFO  (qtp805416817-5867) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1198009 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:36716//collection1|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1518558336623&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1198010 INFO  (qtp523753748-5881) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1518558336623&ids=134,2,147,125,148,105,17,139,130,120&isShard=true&shard.url=http://127.0.0.1:36716//collection1|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1198011 INFO  (qtp523753748-5887) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http:/

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

.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10017_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10019_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10020_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10021_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10033_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10049_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10051_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10065_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10066_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10081_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10082_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10095_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10096_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10104_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10105_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10118_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10120_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          127.0.0.1:10121_solr
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ## Collections:     [testNodeLost]
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ## Max replicas per node:   3
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ## Min replicas per node:   2
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ## Total replicas:          200
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ## * testNodeLost           200
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - active         200
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ######### Final Solr op counts ##########
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - CREATE             1
   [junit4]   2> 2293209 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - DELETESTATUS      40
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - MOVEREPLICA       40
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - REQUESTSTATUS     40
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - autoscaling        1
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - update            15
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ######### Autoscaling event counts ###########
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: .auto_add_replicas
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - IGNORED         1
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - STARTED         2
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - SUCCEEDED       2
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ## * Trigger: node_lost_trigger
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - IGNORED         2
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - STARTED         4
   [junit4]   2> 2293210 INFO  
(TEST-TestLargeCluster.testNodeLost-seed#[3BA892261A2F1034]) [    ] 
o.a.s.c.a.s.SimSolrCloudTestCase ##          - SUCCEEDED       4
   [junit4]   2> 2293210 DEBUG 
(SUITE-TestLargeCluster-seed#[3BA892261A2F1034]-worker) [    ] 
o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
   [junit4]   2> 2293211 WARN  (Simulated OverseerAutoScalingTriggerThread) [   
 ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are 
closed, exiting.
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.sim.TestLargeCluster_3BA892261A2F1034-001
   [junit4]   2> Feb 13, 2018 10:03:52 PM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 45 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {}, 
docValues:{}, maxPointsInLeafNode=1685, maxMBSortInHeap=7.541039568897046, 
sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4391e823),
 locale=sk, timezone=Asia/Hong_Kong
   [junit4]   2> NOTE: Linux 4.4.0-112-generic amd64/Oracle Corporation 
1.8.0_152 (64-bit)/cpus=4,threads=1,free=221390136,total=528482304
   [junit4]   2> NOTE: All tests run in this JVM: 
[UniqFieldsUpdateProcessorFactoryTest, TestConfigSetImmutable, 
TestDeleteCollectionOnDownNodes, TestUtils, TestSchemaSimilarityResource, 
TestBadConfig, NotRequiredUniqueKeyTest, TestReplicaProperties, 
URLClassifyProcessorTest, BasicDistributedZk2Test, DocValuesTest, 
TestCloudPivotFacet, MultiTermTest, BigEndianAscendingWordSerializerTest, 
TestGraphMLResponseWriter, CoreAdminHandlerTest, PingRequestHandlerTest, 
TestConfigSetsAPIExclusivity, TestCollapseQParserPlugin, TestRTimerTree, 
TestGraphTermsQParserPlugin, TestDFRSimilarityFactory, RecoveryZkTest, 
DocValuesMissingTest, TestSkipOverseerOperations, TestFieldTypeResource, 
ConnectionManagerTest, TestCollectionAPIs, ExecutePlanActionTest, 
ForceLeaderTest, DistributedQueueTest, TestScoreJoinQPScore, 
OverriddenZkACLAndCredentialsProvidersTest, FullHLLTest, 
TestInPlaceUpdatesStandalone, CdcrReplicationDistributedZkTest, TestSQLHandler, 
TestStressUserVersions, TestCustomSort, CustomHighlightComponentTest, 
ResponseBuilderTest, TestHttpShardHandlerFactory, CollectionsAPISolrJTest, 
ChangedSchemaMergeTest, TestFastWriter, NoCacheHeaderTest, 
MultiThreadedOCPTest, TestRealTimeGet, SolrCloudExampleTest, 
TestAtomicUpdateErrorCases, TimeZoneUtilsTest, LukeRequestHandlerTest, 
TestStressReorder, ShufflingReplicaListTransformerTest, CopyFieldTest, 
TestCloudSearcherWarming, SolrJmxReporterCloudTest, TestXmlQParserPlugin, 
TestSortableTextField, TestRawResponseWriter, ShardSplitTest, 
HdfsTlogReplayBufferedWhileIndexingTest, DocValuesMultiTest, CircularListTest, 
TestZkAclsWithHadoopAuth, AnalysisErrorHandlingTest, TestManagedSchema, 
StatelessScriptUpdateProcessorFactoryTest, CleanupOldIndexTest, 
TestSuggestSpellingConverter, SparseHLLTest, TestTolerantSearch, 
SearchHandlerTest, JavabinLoaderTest, TestSortByMinMaxFunction, 
TestSolrCloudWithSecureImpersonation, ParsingFieldUpdateProcessorsTest, 
TemplateUpdateProcessorTest, DistanceFunctionTest, CursorMarkTest, 
TestPointFields, HttpPartitionTest, DateFieldTest, TestImplicitCoreProperties, 
TestAnalyzedSuggestions, TestSolrConfigHandlerConcurrent, 
WordBreakSolrSpellCheckerTest, BufferStoreTest, AliasIntegrationTest, 
TestHighFrequencyDictionaryFactory, TestCustomDocTransformer, TestFiltering, 
RestartWhileUpdatingTest, TestDocTermOrds, IndexSchemaRuntimeFieldTest, 
BlockJoinFacetRandomTest, HdfsNNFailoverTest, RankQueryTest, 
DisMaxRequestHandlerTest, CloneFieldUpdateProcessorFactoryTest, 
TestLargeCluster]
   [junit4] Completed [308/776 (2!)] on J2 in 48.95s, 4 tests, 1 failure <<< 
FAILURES!

[...truncated 50052 lines...]

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org