[JENKINS] Lucene-Solr-7.x-Linux (64bit/jdk1.8.0_144) - Build # 1094 - Still Unstable!

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1094/
Java: 64bit/jdk1.8.0_144 -XX:+UseCompressedOops -XX:+UseSerialGC

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=2}

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=2}
        at 
__randomizedtesting.SeedInfo.seed([AF6D089DE5B8BBCA:273937474B44D632]: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.SimpleCollectionCreateDeleteTest.test

Error Message:
Error from server at http://127.0.0.1:42091/_jy/dd: Could not fully create 
collection: collection1

Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:42091/_jy/dd: Could not fully create 
collection: collection1
        at 
__randomizedtesting.SeedInfo.seed([AF6D089DE5B8BBCA:273937474B44D632]:0)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1104)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884)
        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createJettys(AbstractFullDistribZkTestBase.java:390)
        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:339)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:991)
        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)




Build Log:
[...truncated 12661 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/init-core-data-001
   [junit4]   2> 913498 WARN  
(SUITE-TestDistributedSearch-seed#[AF6D089DE5B8BBCA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4
   [junit4]   2> 913498 INFO  
(SUITE-TestDistributedSearch-seed#[AF6D089DE5B8BBCA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 913500 INFO  
(SUITE-TestDistributedSearch-seed#[AF6D089DE5B8BBCA]-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> 913500 INFO  
(SUITE-TestDistributedSearch-seed#[AF6D089DE5B8BBCA]-worker) [    ] 
o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: 
test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 913500 INFO  
(SUITE-TestDistributedSearch-seed#[AF6D089DE5B8BBCA]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 913585 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/control/cores/collection1
   [junit4]   2> 913586 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] o.e.j.s.Server 
jetty-9.3.20.v20170531
   [junit4]   2> 913587 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@386cf8c7{/,null,AVAILABLE}
   [junit4]   2> 913588 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@135f9478{HTTP/1.1,[http/1.1]}{127.0.0.1:38835}
   [junit4]   2> 913588 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] o.e.j.s.Server 
Started @915245ms
   [junit4]   2> 913588 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=38835, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/control/cores}
   [junit4]   2> 913588 ERROR 
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 913588 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 913588 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 913588 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 913588 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-01T07:35:29.194Z
   [junit4]   2> 913589 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/control/solr.xml
   [junit4]   2> 913591 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 913591 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 913592 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@69e4cdfe, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 913624 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69e4cdfe
   [junit4]   2> 913632 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69e4cdfe
   [junit4]   2> 913632 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69e4cdfe
   [junit4]   2> 913633 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/control/cores
   [junit4]   2> 913634 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 913634 INFO  (coreLoadExecutor-2851-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 913634 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 913651 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 913664 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 913734 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 913738 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 913738 INFO  (coreLoadExecutor-2851-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@69e4cdfe
   [junit4]   2> 913738 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 913738 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 913739 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=50.8603515625, 
floorSegmentMB=1.1103515625, forceMergeDeletesPctAllowed=3.0533059236721116, 
segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.21738851897936515
   [junit4]   2> 913769 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 913769 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 913770 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 913770 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 913770 INFO  (coreLoadExecutor-2851-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> 913770 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@445ba008[collection1] main]
   [junit4]   2> 913771 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 913790 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 913876 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 913879 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 913879 INFO  (coreLoadExecutor-2851-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1588374671966863360
   [junit4]   2> 913880 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 913880 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 913880 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 913880 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 913880 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 913880 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 913880 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 913880 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 913881 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@445ba008[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 913959 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 913960 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] o.e.j.s.Server 
jetty-9.3.20.v20170531
   [junit4]   2> 913961 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@2713d9ec{/,null,AVAILABLE}
   [junit4]   2> 913961 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@7c01268b{HTTP/1.1,[http/1.1]}{127.0.0.1:43079}
   [junit4]   2> 913961 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] o.e.j.s.Server 
Started @915618ms
   [junit4]   2> 913961 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=43079, 
coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/shard0/cores}
   [junit4]   2> 913961 ERROR 
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 913962 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.3.0
   [junit4]   2> 913962 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 913962 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 913962 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2018-01-01T07:35:29.568Z
   [junit4]   2> 913962 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 913964 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay 
is ignored
   [junit4]   2> 913964 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.SolrXmlConfig Configuration parameter 
autoReplicaFailoverBadNodeExpiration is ignored
   [junit4]   2> 913964 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@69e4cdfe, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 913981 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69e4cdfe
   [junit4]   2> 913989 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69e4cdfe
   [junit4]   2> 913990 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@69e4cdfe
   [junit4]   2> 913991 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/shard0/cores
   [junit4]   2> 913991 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 913991 INFO  (coreLoadExecutor-2862-thread-1) [    ] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 913992 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib,
 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 914009 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.3.0
   [junit4]   2> 914022 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 914090 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 914094 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 914095 INFO  (coreLoadExecutor-2862-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@69e4cdfe
   [junit4]   2> 914095 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 914095 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/core/src/test-files/solr/collection1],
 
dataDir=[/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_AF6D089DE5B8BBCA-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 914096 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=43, maxMergedSegmentMB=50.8603515625, 
floorSegmentMB=1.1103515625, forceMergeDeletesPctAllowed=3.0533059236721116, 
segmentsPerTier=32.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.21738851897936515
   [junit4]   2> 914121 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 914122 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 914122 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 914122 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 914123 INFO  (coreLoadExecutor-2862-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> 914123 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@17343ce0[collection1] main]
   [junit4]   2> 914123 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 914140 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 914219 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 914219 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 914219 INFO  (coreLoadExecutor-2862-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1588374672323379200
   [junit4]   2> 914219 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 914219 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 914219 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 914219 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 914220 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 914220 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 914220 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 914220 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 914221 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17343ce0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 914222 INFO  
(TEST-TestDistributedSearch.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 914224 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1588374672327573504)} 0 1
   [junit4]   2> 914226 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1588374672329670656)} 0 0
   [junit4]   2> 914228 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1588374672331767808)]} 0 1
   [junit4]   2> 914244 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1588374672332816384)]} 0 16
   [junit4]   2> 914245 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1588374672350642176)]} 0 0
   [junit4]   2> 914246 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[2 (1588374672350642176)]} 0 0
   [junit4]   2> 914246 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1588374672351690752)]} 0 0
   [junit4]   2> 914246 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[3 (1588374672351690752)]} 0 0
   [junit4]   2> 914247 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1588374672352739328)]} 0 0
   [junit4]   2> 914247 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[4 (1588374672352739328)]} 0 0
   [junit4]   2> 914248 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1588374672353787904)]} 0 0
   [junit4]   2> 914248 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[5 (1588374672353787904)]} 0 0
   [junit4]   2> 914248 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1588374672353787905)]} 0 0
   [junit4]   2> 914248 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[6 (1588374672353787905)]} 0 0
   [junit4]   2> 914249 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1588374672354836480)]} 0 0
   [junit4]   2> 914249 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[7 (1588374672354836480)]} 0 0
   [junit4]   2> 914249 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1588374672354836481)]} 0 0
   [junit4]   2> 914250 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[8 (1588374672355885056)]} 0 0
   [junit4]   2> 914250 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1588374672355885056)]} 0 0
   [junit4]   2> 914250 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[9 (1588374672355885057)]} 0 0
   [junit4]   2> 914251 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1588374672356933632,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 914251 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5f50c15a 
commitCommandVersion:1588374672356933632
   [junit4]   2> 914255 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@7fb84ee5[collection1] main]
   [junit4]   2> 914256 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 914257 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7fb84ee5[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c9)))}
   [junit4]   2> 914257 INFO  (qtp765231698-7669) [    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 6
   [junit4]   2> 914257 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1588374672363225088,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 914257 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7b1839c8 
commitCommandVersion:1588374672363225088
   [junit4]   2> 914260 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3f211e02[collection1] main]
   [junit4]   2> 914260 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 914261 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3f211e02[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c9)))}
   [junit4]   2> 914261 INFO  (qtp1334241519-7686) [    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 4
   [junit4]   2> 914263 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1588374672368467968)]} 0 1
   [junit4]   2> 914264 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[10 (1588374672369516544)]} 0 0
   [junit4]   2> 914264 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1588374672370565120)]} 0 0
   [junit4]   2> 914265 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[11 (1588374672371613696)]} 0 0
   [junit4]   2> 914265 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1588374672371613696)]} 0 0
   [junit4]   2> 914265 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[12 (1588374672371613697)]} 0 0
   [junit4]   2> 914266 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1588374672371613697)]} 0 0
   [junit4]   2> 914266 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[13 (1588374672372662272)]} 0 0
   [junit4]   2> 914267 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1588374672372662272)]} 0 0
   [junit4]   2> 914267 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1001 (1588374672373710848)]} 0 0
   [junit4]   2> 914267 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1588374672373710848)]} 0 0
   [junit4]   2> 914268 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[14 (1588374672374759424)]} 0 0
   [junit4]   2> 914268 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1588374672374759424)]} 0 0
   [junit4]   2> 914268 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[15 (1588374672374759425)]} 0 0
   [junit4]   2> 914269 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1588374672375808000)]} 0 0
   [junit4]   2> 914269 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[16 (1588374672375808000)]} 0 0
   [junit4]   2> 914269 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1588374672375808001)]} 0 0
   [junit4]   2> 914270 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[17 (1588374672376856576)]} 0 0
   [junit4]   2> 914270 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1588374672376856576)]} 0 0
   [junit4]   2> 914270 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[100 (1588374672376856577)]} 0 0
   [junit4]   2> 914271 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1588374672377905152)]} 0 0
   [junit4]   2> 914271 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[101 (1588374672377905152)]} 0 0
   [junit4]   2> 914271 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1588374672377905153)]} 0 0
   [junit4]   2> 914271 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[102 (1588374672377905153)]} 0 0
   [junit4]   2> 914272 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1588374672378953728)]} 0 0
   [junit4]   2> 914272 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[103 (1588374672378953728)]} 0 0
   [junit4]   2> 914272 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1588374672378953729)]} 0 0
   [junit4]   2> 914272 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[104 (1588374672378953729)]} 0 0
   [junit4]   2> 914273 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1588374672378953730)]} 0 0
   [junit4]   2> 914273 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[105 (1588374672380002304)]} 0 0
   [junit4]   2> 914273 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1588374672380002304)]} 0 0
   [junit4]   2> 914273 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[106 (1588374672380002305)]} 0 0
   [junit4]   2> 914274 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1588374672380002305)]} 0 0
   [junit4]   2> 914274 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[107 (1588374672381050880)]} 0 0
   [junit4]   2> 914274 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1588374672381050880)]} 0 0
   [junit4]   2> 914274 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[108 (1588374672381050881)]} 0 0
   [junit4]   2> 914275 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1588374672382099456)]} 0 0
   [junit4]   2> 914275 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[109 (1588374672382099456)]} 0 0
   [junit4]   2> 914275 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1588374672382099457)]} 0 0
   [junit4]   2> 914275 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[110 (1588374672382099457)]} 0 0
   [junit4]   2> 914276 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1588374672383148032)]} 0 0
   [junit4]   2> 914276 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[111 (1588374672383148032)]} 0 0
   [junit4]   2> 914276 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1588374672383148033)]} 0 0
   [junit4]   2> 914276 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[112 (1588374672383148033)]} 0 0
   [junit4]   2> 914277 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1588374672384196608)]} 0 0
   [junit4]   2> 914277 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[113 (1588374672384196608)]} 0 0
   [junit4]   2> 914277 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1588374672384196609)]} 0 0
   [junit4]   2> 914277 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[114 (1588374672384196609)]} 0 0
   [junit4]   2> 914278 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1588374672385245184)]} 0 0
   [junit4]   2> 914278 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[115 (1588374672385245184)]} 0 0
   [junit4]   2> 914278 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1588374672385245185)]} 0 0
   [junit4]   2> 914278 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[116 (1588374672385245185)]} 0 0
   [junit4]   2> 914279 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1588374672386293760)]} 0 0
   [junit4]   2> 914279 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[117 (1588374672386293760)]} 0 0
   [junit4]   2> 914279 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1588374672386293761)]} 0 0
   [junit4]   2> 914279 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[118 (1588374672386293761)]} 0 0
   [junit4]   2> 914280 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1588374672387342336)]} 0 0
   [junit4]   2> 914280 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[119 (1588374672387342336)]} 0 0
   [junit4]   2> 914280 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1588374672387342337)]} 0 0
   [junit4]   2> 914280 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[120 (1588374672387342337)]} 0 0
   [junit4]   2> 914281 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1588374672388390912)]} 0 0
   [junit4]   2> 914281 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[121 (1588374672388390912)]} 0 0
   [junit4]   2> 914281 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1588374672388390913)]} 0 0
   [junit4]   2> 914281 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[122 (1588374672388390913)]} 0 0
   [junit4]   2> 914282 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1588374672388390914)]} 0 0
   [junit4]   2> 914282 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[123 (1588374672389439488)]} 0 0
   [junit4]   2> 914282 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1588374672389439488)]} 0 0
   [junit4]   2> 914282 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[124 (1588374672389439489)]} 0 0
   [junit4]   2> 914283 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1588374672390488064)]} 0 0
   [junit4]   2> 914283 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[125 (1588374672390488064)]} 0 0
   [junit4]   2> 914283 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1588374672390488065)]} 0 0
   [junit4]   2> 914283 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[126 (1588374672390488065)]} 0 0
   [junit4]   2> 914284 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1588374672391536640)]} 0 0
   [junit4]   2> 914284 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[127 (1588374672391536640)]} 0 0
   [junit4]   2> 914284 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1588374672391536641)]} 0 0
   [junit4]   2> 914284 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[128 (1588374672391536641)]} 0 0
   [junit4]   2> 914285 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1588374672392585216)]} 0 0
   [junit4]   2> 914285 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[129 (1588374672392585216)]} 0 0
   [junit4]   2> 914285 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1588374672392585217)]} 0 0
   [junit4]   2> 914285 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[130 (1588374672392585217)]} 0 0
   [junit4]   2> 914286 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1588374672393633792)]} 0 0
   [junit4]   2> 914286 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[131 (1588374672393633792)]} 0 0
   [junit4]   2> 914286 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1588374672393633793)]} 0 0
   [junit4]   2> 914286 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[132 (1588374672393633793)]} 0 0
   [junit4]   2> 914287 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1588374672394682368)]} 0 0
   [junit4]   2> 914287 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[133 (1588374672394682368)]} 0 0
   [junit4]   2> 914287 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1588374672394682369)]} 0 0
   [junit4]   2> 914288 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[134 (1588374672395730944)]} 0 0
   [junit4]   2> 914288 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1588374672395730944)]} 0 0
   [junit4]   2> 914288 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[135 (1588374672395730945)]} 0 0
   [junit4]   2> 914288 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1588374672395730945)]} 0 0
   [junit4]   2> 914289 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[136 (1588374672396779520)]} 0 0
   [junit4]   2> 914289 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1588374672396779520)]} 0 0
   [junit4]   2> 914289 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[137 (1588374672396779521)]} 0 0
   [junit4]   2> 914290 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1588374672397828096)]} 0 0
   [junit4]   2> 914290 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[138 (1588374672397828096)]} 0 0
   [junit4]   2> 914290 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1588374672397828097)]} 0 0
   [junit4]   2> 914291 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[139 (1588374672397828097)]} 0 0
   [junit4]   2> 914291 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1588374672398876672)]} 0 0
   [junit4]   2> 914291 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[140 (1588374672398876672)]} 0 0
   [junit4]   2> 914291 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1588374672398876673)]} 0 0
   [junit4]   2> 914292 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[141 (1588374672399925248)]} 0 0
   [junit4]   2> 914292 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1588374672399925248)]} 0 0
   [junit4]   2> 914292 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[142 (1588374672399925249)]} 0 0
   [junit4]   2> 914292 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1588374672399925249)]} 0 0
   [junit4]   2> 914293 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[143 (1588374672400973824)]} 0 0
   [junit4]   2> 914293 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1588374672400973824)]} 0 0
   [junit4]   2> 914293 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[144 (1588374672400973825)]} 0 0
   [junit4]   2> 914294 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1588374672400973825)]} 0 0
   [junit4]   2> 914294 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[145 (1588374672402022400)]} 0 0
   [junit4]   2> 914294 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1588374672402022400)]} 0 0
   [junit4]   2> 914294 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[146 (1588374672402022401)]} 0 0
   [junit4]   2> 914295 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1588374672403070976)]} 0 0
   [junit4]   2> 914295 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[147 (1588374672403070976)]} 0 0
   [junit4]   2> 914295 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1588374672403070977)]} 0 0
   [junit4]   2> 914296 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[148 (1588374672403070977)]} 0 0
   [junit4]   2> 914296 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1588374672404119552)]} 0 0
   [junit4]   2> 914296 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[149 (1588374672404119552)]} 0 0
   [junit4]   2> 914296 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1588374672404119553,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 914296 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@5f50c15a 
commitCommandVersion:1588374672404119553
   [junit4]   2> 914301 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@669f9535[collection1] main]
   [junit4]   2> 914301 INFO  (qtp765231698-7669) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 914302 INFO  
(searcherExecutor-2852-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@669f9535[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c9)
 Uninverting(_1(7.3.0):c59)))}
   [junit4]   2> 914302 INFO  (qtp765231698-7669) [    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 5
   [junit4]   2> 914302 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1588374672410411008,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 914302 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7b1839c8 
commitCommandVersion:1588374672410411008
   [junit4]   2> 914308 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@26d63a6c[collection1] main]
   [junit4]   2> 914308 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 914310 INFO  
(searcherExecutor-2863-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@26d63a6c[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.3.0):c9)
 Uninverting(_1(7.3.0):c59)))}
   [junit4]   2> 914310 INFO  (qtp1334241519-7686) [    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 7
   [junit4]   2> 914311 INFO  (qtp765231698-7662) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 914311 INFO  (qtp765231698-7662) [    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> 914312 INFO  (qtp765231698-7667) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 914312 INFO  (qtp765231698-7667) [    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> 914312 INFO  (qtp765231698-7668) [    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> 914315 INFO  (qtp1334241519-7685) [    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:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792129920&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914316 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129920&ids=143,110,100,144,147,125,17,107,118,142&isShard=true&shard.url=http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914318 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:43079//collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 914319 INFO  (qtp765231698-7669) [    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> 914333 INFO  (qtp1334241519-7680) [    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::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:43079//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1514792129926&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914334 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129926&ids=14,102,146,15,104,126,119,9,140,131&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:43079//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914337 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|http://127.0.0.1:43079//collection1|[ff01::114]:33332/&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=16
   [junit4]   2> 914337 INFO  (qtp765231698-7662) [    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> 914339 INFO  (qtp1334241519-7684) [    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::213]:33332/|http://127.0.0.1:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792129945&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914342 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129945&ids=143,1,2,135,104,127,116,8,9,141&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914345 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:43079//collection1&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 914346 INFO  (qtp765231698-7667) [    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> 914348 INFO  (qtp1334241519-7679) [    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=[ff01::213]:33332/|http://127.0.0.1:43079//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1514792129953&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914350 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129953&ids=122,12,134,103,4,5,137,7,131,120&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:43079//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914351 INFO  (qtp1334241519-7680) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:43079//collection1|[ff01::114]:33332/&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 914352 INFO  (qtp765231698-7668) [    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> 914359 INFO  (qtp1334241519-7685) [    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=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792129958&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914360 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129958&ids=11,100,2,124,113,4,5,1001,7,119&isShard=true&shard.url=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914362 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/|http://127.0.0.1:43079//collection1&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=9
   [junit4]   2> 914363 INFO  (qtp765231698-7669) [    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> 914365 INFO  (qtp1334241519-7680) [    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:43079//collection1|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1514792129970&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914366 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129970&ids=111,123,114,125,15,105,9,130,142,120&isShard=true&shard.url=[ff01::083]:33332/|http://127.0.0.1:43079//collection1|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914367 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|http://127.0.0.1:43079//collection1|[ff01::114]:33332/&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 914368 INFO  (qtp765231698-7662) [    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> 914370 INFO  (qtp1334241519-7684) [    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=[ff01::114]:33332/|http://127.0.0.1:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792129975&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914370 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129975&ids=12,122,144,103,126,5,1001,130,142,120&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914371 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:43079//collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 914372 INFO  (qtp765231698-7667) [    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> 914373 INFO  (qtp1334241519-7686) [    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:43079//collection1|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1514792129979&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914374 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129979&ids=13,146,147,148,115,17,128,118,108,9&isShard=true&shard.url=http://127.0.0.1:43079//collection1|[ff01::213]:33332/|[ff01::114]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914375 INFO  (qtp1334241519-7680) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:43079//collection1|[ff01::213]:33332/|[ff01::114]:33332/&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 914376 INFO  (qtp765231698-7668) [    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> 914378 INFO  (qtp1334241519-7685) [    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=http://127.0.0.1:43079//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1514792129983&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914379 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129983&ids=122,100,2,13,3,113,17,129,108,140&isShard=true&shard.url=http://127.0.0.1:43079//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914379 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:43079//collection1|[ff01::114]:33332/|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 914380 INFO  (qtp765231698-7669) [    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> 914382 INFO  (qtp1334241519-7680) [    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=http://127.0.0.1:43079//collection1|[ff01::083]:33332/|[ff01::213]:33332/&rows=10&version=2&q=*:*&NOW=1514792129987&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914382 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129987&ids=111,1,135,14,114,126,148,149,9,130&isShard=true&shard.url=http://127.0.0.1:43079//collection1|[ff01::083]:33332/|[ff01::213]:33332/&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914383 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:43079//collection1|[ff01::083]:33332/|[ff01::213]:33332/&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 914384 INFO  (qtp765231698-7662) [    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> 914385 INFO  (qtp1334241519-7684) [    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/|[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792129991&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914386 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129991&ids=143,111,123,3,102,103,125,17,7,109&isShard=true&shard.url=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914387 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|[ff01::083]:33332/|[ff01::114]:33332/|http://127.0.0.1:43079//collection1&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 914387 INFO  (qtp765231698-7662) [    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> 914388 INFO  (qtp1334241519-7679) [    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::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792129994&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914389 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129994&ids=11,12,13,15,4,126,148,1001,140,130&isShard=true&shard.url=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914390 INFO  (qtp1334241519-7680) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::083]:33332/|[ff01::114]:33332/|[ff01::213]:33332/|http://127.0.0.1:43079//collection1&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 914391 INFO  (qtp765231698-7668) [    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> 914392 INFO  (qtp1334241519-7685) [    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=[ff01::213]:33332/|http://127.0.0.1:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792129997&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914393 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792129997&ids=121,134,126,137,138,6,17,129,9,120&isShard=true&shard.url=[ff01::213]:33332/|http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914393 INFO  (qtp1334241519-7684) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::213]:33332/|http://127.0.0.1:43079//collection1&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 914394 INFO  (qtp765231698-7669) [    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> 914395 INFO  (qtp1334241519-7680) [    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:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792130001&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914396 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792130001&ids=143,144,136,15,115,148,107,108,142,131&isShard=true&shard.url=http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914396 INFO  (qtp1334241519-7679) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:43079//collection1&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 914397 INFO  (qtp765231698-7667) [    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> 914398 INFO  (qtp1334241519-7684) [    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=http://127.0.0.1:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792130004&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914399 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792130004&ids=146,124,4,114,5,17,149,117,1001,10&isShard=true&shard.url=http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914400 INFO  (qtp1334241519-7685) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=http://127.0.0.1:43079//collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 914400 INFO  (qtp765231698-7662) [    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> 914401 INFO  (qtp1334241519-7679) [    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=[ff01::114]:33332/|http://127.0.0.1:43079//collection1&rows=10&version=2&q=*:*&NOW=1514792130007&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914402 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1514792130007&ids=143,101,113,15,136,106,128,107,118,108&isShard=true&shard.url=[ff01::114]:33332/|http://127.0.0.1:43079//collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 914403 INFO  (qtp1334241519-7680) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&shards=[ff01::114]:33332/|http://127.0.0.1:43079//collection1&sort=n_dt1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 914404 INFO  (qtp765231698-7668) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&distrib=false&sort=n_tdt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 914405 INFO  (qtp1334241519-7685) [    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_tdt1+desc&shard.url=http://127.0.0.1:43079//collection1|[ff01::083]:33332/|[ff01::213]:33332/|[ff01::114]:33332/&rows=10&version=2&q=*:*&NOW=1514792130011&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 914406 INFO  (qtp1334241519-7686) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=15147921300

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

eleteTest.test-seed#[AF6D089DE5B8BBCA]) [    ] o.a.s.m.r.SolrJmxReporter 
Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1cd6f5c8: 
rootName = null, domain = solr.jetty, service url = null, agent id = null] for 
registry solr.jetty / com.codahale.metrics.MetricRegistry@5c636b3e
   [junit4]   2> 1003377 INFO  
(TEST-SimpleCollectionCreateDeleteTest.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.ZkController Remove node as live in 
ZooKeeper:/live_nodes/127.0.0.1:42091__jy%2Fdd
   [junit4]   2> 1003378 INFO  
(TEST-SimpleCollectionCreateDeleteTest.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, 
tag=null
   [junit4]   2> 1003378 INFO  
(zkCallback-1661-thread-2-processing-n:127.0.0.1:42091__jy%2Fdd) 
[n:127.0.0.1:42091__jy%2Fdd    ] o.a.s.c.c.ZkStateReader Updated live nodes 
from ZooKeeper... (1) -> (0)
   [junit4]   2> 1003382 INFO  (coreCloseExecutor-2968-thread-1) 
[n:127.0.0.1:42091__jy%2Fdd c:control_collection s:shard1 r:core_node2 
x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore 
[control_collection_shard1_replica_n1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@76318867
   [junit4]   2> 1003382 INFO  (coreCloseExecutor-2968-thread-1) 
[n:127.0.0.1:42091__jy%2Fdd c:control_collection s:shard1 r:core_node2 
x:control_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing 
metric reporters for registry=solr.core.control_collection.shard1.replica_n1, 
tag=1982957671
   [junit4]   2> 1003382 INFO  (coreCloseExecutor-2968-thread-1) 
[n:127.0.0.1:42091__jy%2Fdd c:control_collection s:shard1 r:core_node2 
x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing 
reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@46e91fe: rootName = 
null, domain = solr.core.control_collection.shard1.replica_n1, service url = 
null, agent id = null] for registry 
solr.core.control_collection.shard1.replica_n1 / 
com.codahale.metrics.MetricRegistry@468d7b0
   [junit4]   2> 1003390 INFO  (coreCloseExecutor-2968-thread-1) 
[n:127.0.0.1:42091__jy%2Fdd c:control_collection s:shard1 r:core_node2 
x:control_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing 
metric reporters for registry=solr.collection.control_collection.shard1.leader, 
tag=1982957671
   [junit4]   2> 1003394 INFO  
(TEST-SimpleCollectionCreateDeleteTest.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.Overseer Overseer 
(id=99273418797744132-127.0.0.1:42091__jy%2Fdd-n_0000000000) closing
   [junit4]   2> 1003394 INFO  
(OverseerStateUpdate-99273418797744132-127.0.0.1:42091__jy%2Fdd-n_0000000000) 
[n:127.0.0.1:42091__jy%2Fdd    ] o.a.s.c.Overseer Overseer Loop exiting : 
127.0.0.1:42091__jy%2Fdd
   [junit4]   2> 1003401 WARN  
(OverseerAutoScalingTriggerThread-99273418797744132-127.0.0.1:42091__jy%2Fdd-n_0000000000)
 [n:127.0.0.1:42091__jy%2Fdd    ] o.a.s.c.a.OverseerTriggerThread 
OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 1003408 INFO  
(TEST-SimpleCollectionCreateDeleteTest.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@1183ac3c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 1003408 INFO  
(TEST-SimpleCollectionCreateDeleteTest.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@624fd247{/_jy/dd,null,UNAVAILABLE}
   [junit4]   2> 1003409 ERROR 
(TEST-SimpleCollectionCreateDeleteTest.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper 
server won't take any action on ERROR or SHUTDOWN server state changes
   [junit4]   2> 1003409 INFO  
(TEST-SimpleCollectionCreateDeleteTest.test-seed#[AF6D089DE5B8BBCA]) [    ] 
o.a.s.c.ZkTestServer connecting to 127.0.0.1:46187 46187
   [junit4]   2> 1003527 INFO  (Thread-1628) [    ] o.a.s.c.ZkTestServer 
connecting to 127.0.0.1:46187 46187
   [junit4]   2> 1003540 WARN  (Thread-1628) [    ] o.a.s.c.ZkTestServer Watch 
limit violations: 
   [junit4]   2> Maximum concurrent create/delete watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/aliases.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent data watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/clusterstate.json
   [junit4]   2>        3       /solr/clusterprops.json
   [junit4]   2> 
   [junit4]   2> Maximum concurrent children watches above limit:
   [junit4]   2> 
   [junit4]   2>        3       /solr/live_nodes
   [junit4]   2>        3       /solr/collections
   [junit4]   2> 
   [junit4]   2> NOTE: reproduce with: ant test  
-Dtestcase=SimpleCollectionCreateDeleteTest -Dtests.method=test 
-Dtests.seed=AF6D089DE5B8BBCA -Dtests.multiplier=3 -Dtests.slow=true 
-Dtests.locale=es-DO -Dtests.timezone=America/Argentina/San_Luis 
-Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   62.2s J0 | SimpleCollectionCreateDeleteTest.test <<<
   [junit4]    > Throwable #1: 
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error 
from server at http://127.0.0.1:42091/_jy/dd: Could not fully create 
collection: collection1
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([AF6D089DE5B8BBCA:273937474B44D632]:0)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1104)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:884)
   [junit4]    >        at 
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:817)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
   [junit4]    >        at 
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
   [junit4]    >        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createJettys(AbstractFullDistribZkTestBase.java:390)
   [junit4]    >        at 
org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:339)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:991)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.SimpleCollectionCreateDeleteTest_AF6D089DE5B8BBCA-001
   [junit4]   2> Jan 01, 2018 7:36:59 AM 
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
   [junit4]   2> WARNING: Will linger awaiting termination of 1 leaked 
thread(s).
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=false): {}, locale=es-DO, 
timezone=America/Argentina/San_Luis
   [junit4]   2> NOTE: Linux 4.10.0-40-generic amd64/Oracle Corporation 
1.8.0_144 (64-bit)/cpus=8,threads=1,free=123574544,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [ExecutePlanActionTest, 
DocValuesTest, TestConfigsApi, TestRequestStatusCollectionAPI, 
TestTestInjection, AddBlockUpdateTest, TestDocBasedVersionConstraints, 
BooleanFieldTest, DirectUpdateHandlerOptimizeTest, TestBulkSchemaAPI, 
TestSolrCloudWithDelegationTokens, TestXmlQParser, TestFilteredDocIdSet, 
SyncSliceTest, TestReRankQParserPlugin, TestSQLHandlerNonCloud, 
TestReqParamsAPI, TestDownShardTolerantSearch, 
StatelessScriptUpdateProcessorFactoryTest, TestSolrJ, TestCSVLoader, 
TestSolrIndexConfig, TestBinaryField, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestMergePolicyConfig, 
TestEmbeddedSolrServerSchemaAPI, SpellCheckComponentTest, 
HdfsRestartWhileUpdatingTest, TestRandomDVFaceting, HdfsNNFailoverTest, 
GraphQueryTest, TestRandomFaceting, DistributedSuggestComponentTest, 
TestNumericTerms32, TestDFRSimilarityFactory, ManagedSchemaRoundRobinCloudTest, 
ForceLeaderTest, TestIndexingPerformance, BasicFunctionalityTest, 
CacheHeaderTest, TestLargeCluster, TestCrossCoreJoin, 
TestWordDelimiterFilterFactory, HdfsTlogReplayBufferedWhileIndexingTest, 
AssignBackwardCompatibilityTest, TestPHPSerializedResponseWriter, 
BlockDirectoryTest, TestLocalFSCloudBackupRestore, AtomicUpdatesTest, 
TestSuggestSpellingConverter, UpdateRequestProcessorFactoryTest, 
HdfsChaosMonkeyNothingIsSafeTest, TestComplexPhraseQParserPlugin, 
TestConfigSetsAPI, VersionInfoTest, SolrGraphiteReporterTest, 
DistributedFacetPivotLongTailTest, MultiTermTest, TestCloudPseudoReturnFields, 
SoftAutoCommitTest, SearchRateTriggerTest, 
SuggestComponentContextFilterQueryTest, TestPostingsSolrHighlighter, 
MoveReplicaTest, TestMissingGroups, TestFieldCacheVsDocValues, 
SaslZkACLProviderTest, TestBadConfig, TestSolrCloudSnapshots, 
TestSolrDeletionPolicy1, TestAnalyzeInfixSuggestions, 
AutoscalingHistoryHandlerTest, DirectoryFactoryTest, RulesTest, 
TestRetrieveFieldsOptimizer, ZkControllerTest, DistributedMLTComponentTest, 
TestRebalanceLeaders, TestSerializedLuceneMatchVersion, 
TestMultiValuedNumericRangeQuery, AnalysisAfterCoreReloadTest, 
MissingSegmentRecoveryTest, TestSort, DocumentBuilderTest, JsonLoaderTest, 
OutOfBoxZkACLAndCredentialsProvidersTest, CursorPagingTest, DeleteReplicaTest, 
DeleteInactiveReplicaTest, TestDistributedSearch, BasicZkTest, 
TestFieldTypeCollectionResource, TestSubQueryTransformer, 
SimpleCollectionCreateDeleteTest]
   [junit4] Completed [336/764 (2!)] on J0 in 63.26s, 1 test, 1 error <<< 
FAILURES!

[...truncated 49556 lines...]

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