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

[Policeman Jenkins Server]

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Solaris/54/
Java: 64bit/jdk1.8.0 -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:1142) 
 at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) 
 at java.lang.Thread.run(Thread.java:748) ,time=1}

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:1142)
        at 
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:748)
,time=1}
        at 
__randomizedtesting.SeedInfo.seed([7591DF3462BA620E:FDC5E0EECC460FF6]: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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1011)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:960)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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.update.AutoCommitTest.testCommitWithin

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
        at 
__randomizedtesting.SeedInfo.seed([7591DF3462BA620E:CF43B04CE1948C1B]:0)
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:878)
        at 
org.apache.solr.update.AutoCommitTest.testCommitWithin(AutoCommitTest.java:353)
        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:1713)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
        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:916)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
        at 
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
        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)
Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result[@numFound=1]
        xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="0" 
start="0"></result>
</response>

        request was:q=id:529&qt=&start=0&rows=20&version=2.2
        at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:871)
        ... 40 more




Build Log:
[...truncated 11852 lines...]
   [junit4] Suite: org.apache.solr.TestDistributedSearch
   [junit4]   2> Creating dataDir: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/init-core-data-001
   [junit4]   2> 1630787 INFO  
(SUITE-TestDistributedSearch-seed#[7591DF3462BA620E]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) 
w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1630788 INFO  
(SUITE-TestDistributedSearch-seed#[7591DF3462BA620E]-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> 1630788 INFO  
(SUITE-TestDistributedSearch-seed#[7591DF3462BA620E]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_enk/f
   [junit4]   2> 1630902 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/control/cores/collection1
   [junit4]   2> 1630903 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 1630904 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3e7307d6{/_enk/f,null,AVAILABLE}
   [junit4]   2> 1630904 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@734de992{HTTP/1.1,[http/1.1]}{127.0.0.1:36848}
   [junit4]   2> 1630905 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] o.e.j.s.Server 
Started @1634120ms
   [junit4]   2> 1630905 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_enk/f, 
hostPort=36848, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/control/cores}
   [junit4]   2> 1630905 ERROR 
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1630905 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 1630905 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1630905 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1630905 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-19T17:36:06.116Z
   [junit4]   2> 1630905 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/control/solr.xml
   [junit4]   2> 1630908 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@29fe6539, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1630944 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fe6539
   [junit4]   2> 1630946 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fe6539
   [junit4]   2> 1630947 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fe6539
   [junit4]   2> 1630948 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/control/cores
   [junit4]   2> 1630948 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1630949 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib,
 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1630995 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1631022 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1631094 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1631100 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1631100 INFO  (coreLoadExecutor-7183-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@29fe6539
   [junit4]   2> 1631100 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1631100 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 1631101 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=25.8876953125, 
floorSegmentMB=0.578125, forceMergeDeletesPctAllowed=22.70174469778887, 
segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1631147 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1631147 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1631148 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1631148 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1631149 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=28, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=94.78515625, 
floorSegmentMB=1.30078125, forceMergeDeletesPctAllowed=25.045788435906616, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3837181628131596
   [junit4]   2> 1631149 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@5b292fe9[collection1] main]
   [junit4]   2> 1631149 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1631153 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1631167 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1631168 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1631168 INFO  (coreLoadExecutor-7183-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1573373362966626304
   [junit4]   2> 1631168 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1631169 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1631169 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1631169 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1631169 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1631170 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1631170 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1631170 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1631171 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@5b292fe9[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1631579 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 1631580 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] o.e.j.s.Server 
jetty-9.3.14.v20161028
   [junit4]   2> 1631596 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@168eb217{/_enk/f,null,AVAILABLE}
   [junit4]   2> 1631596 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@18e6c2b5{HTTP/1.1,[http/1.1]}{127.0.0.1:64223}
   [junit4]   2> 1631596 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] o.e.j.s.Server 
Started @1634812ms
   [junit4]   2> 1631596 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_enk/f, 
hostPort=64223, 
coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/shard0/cores}
   [junit4]   2> 1631597 ERROR 
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 1631597 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
7.1.0
   [junit4]   2> 1631597 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 1631597 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null, Default config 
dir: null
   [junit4]   2> 1631597 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-07-19T17:36:06.808Z
   [junit4]   2> 1631598 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 1631600 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.SolrXmlConfig MBean server found: 
com.sun.jmx.mbeanserver.JmxMBeanServer@29fe6539, but no JMX reporters were 
configured - adding default JMX reporter.
   [junit4]   2> 1631626 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fe6539
   [junit4]   2> 1631629 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') 
enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fe6539
   [junit4]   2> 1631629 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 
'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@29fe6539
   [junit4]   2> 1631630 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/shard0/cores
   [junit4]   2> 1631630 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 1631631 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib,
 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 1631653 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.1.0
   [junit4]   2> 1631676 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1631772 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 1631778 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 1631778 INFO  (coreLoadExecutor-7194-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@29fe6539
   [junit4]   2> 1631778 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 1631778 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/core/src/test-files/solr/collection1],
 
dataDir=[/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.TestDistributedSearch_7591DF3462BA620E-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 1631779 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=20, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=25.8876953125, 
floorSegmentMB=0.578125, forceMergeDeletesPctAllowed=22.70174469778887, 
segmentsPerTier=33.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
   [junit4]   2> 1631820 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 1631820 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 1631821 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 1631821 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 1631823 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=28, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=94.78515625, 
floorSegmentMB=1.30078125, forceMergeDeletesPctAllowed=25.045788435906616, 
segmentsPerTier=27.0, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=0.3837181628131596
   [junit4]   2> 1631823 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@7acfa9a4[collection1] main]
   [junit4]   2> 1631825 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1631829 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 1631842 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1631843 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1631843 INFO  (coreLoadExecutor-7194-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1573373363674415104
   [junit4]   2> 1631843 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1631843 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1631843 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 1631844 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1631844 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1631844 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1631844 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1631844 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1631845 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@7acfa9a4[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1632145 INFO  
(TEST-TestDistributedSearch.test-seed#[7591DF3462BA620E]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 1632150 INFO  (qtp1564526762-20369) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1573373363994230784)} 0 2
   [junit4]   2> 1632156 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1573373363999473664)} 0 3
   [junit4]   2> 1632159 INFO  (qtp1564526762-20368) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[1 (1573373364003667968)]} 0 1
   [junit4]   2> 1632161 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[1 (1573373364006813696)]} 0 1
   [junit4]   2> 1632163 INFO  (qtp1564526762-20370) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[2 (1573373364008910848)]} 0 0
   [junit4]   2> 1632165 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[2 (1573373364011008000)]} 0 1
   [junit4]   2> 1632166 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[3 (1573373364012056576)]} 0 0
   [junit4]   2> 1632168 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[3 (1573373364014153728)]} 0 1
   [junit4]   2> 1632169 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[4 (1573373364016250880)]} 0 0
   [junit4]   2> 1632171 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[4 (1573373364017299456)]} 0 1
   [junit4]   2> 1632172 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[5 (1573373364019396608)]} 0 0
   [junit4]   2> 1632174 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[5 (1573373364020445184)]} 0 1
   [junit4]   2> 1632175 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[6 (1573373364021493760)]} 0 0
   [junit4]   2> 1632176 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[6 (1573373364022542336)]} 0 0
   [junit4]   2> 1632177 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[7 (1573373364023590912)]} 0 0
   [junit4]   2> 1632178 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[7 (1573373364024639488)]} 0 0
   [junit4]   2> 1632179 INFO  (qtp1564526762-20368) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[8 (1573373364026736640)]} 0 0
   [junit4]   2> 1632181 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[8 (1573373364027785216)]} 0 0
   [junit4]   2> 1632182 INFO  (qtp1564526762-20370) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[9 (1573373364028833792)]} 0 0
   [junit4]   2> 1632183 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[9 (1573373364030930944)]} 0 0
   [junit4]   2> 1632184 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573373364031979520,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1632184 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7b41116e 
commitCommandVersion:1573373364031979520
   [junit4]   2> 1632190 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@f394738[collection1] main]
   [junit4]   2> 1632190 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1632191 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@f394738[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c9)))}
   [junit4]   2> 1632191 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 1632192 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573373364040368128,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1632192 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6f0b2ce1 
commitCommandVersion:1573373364040368128
   [junit4]   2> 1632197 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@32e061d[collection1] main]
   [junit4]   2> 1632198 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1632199 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@32e061d[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c9)))}
   [junit4]   2> 1632199 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 7
   [junit4]   2> 1632203 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[10 (1573373364048756736)]} 0 2
   [junit4]   2> 1632205 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[10 (1573373364051902464)]} 0 2
   [junit4]   2> 1632209 INFO  (qtp1564526762-20366) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[11 (1573373364055048192)]} 0 2
   [junit4]   2> 1632211 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[11 (1573373364059242496)]} 0 1
   [junit4]   2> 1632212 INFO  (qtp1564526762-20369) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[12 (1573373364061339648)]} 0 0
   [junit4]   2> 1632214 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[12 (1573373364062388224)]} 0 0
   [junit4]   2> 1632215 INFO  (qtp1564526762-20369) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[13 (1573373364063436800)]} 0 0
   [junit4]   2> 1632216 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[13 (1573373364064485376)]} 0 0
   [junit4]   2> 1632218 INFO  (qtp1564526762-20368) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[1001 (1573373364066582528)]} 0 0
   [junit4]   2> 1632219 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[1001 (1573373364067631104)]} 0 0
   [junit4]   2> 1632219 INFO  (qtp1564526762-20370) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[14 (1573373364068679680)]} 0 0
   [junit4]   2> 1632220 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[14 (1573373364069728256)]} 0 0
   [junit4]   2> 1632221 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[15 (1573373364070776832)]} 0 0
   [junit4]   2> 1632222 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[15 (1573373364070776832)]} 0 0
   [junit4]   2> 1632222 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[16 (1573373364071825408)]} 0 0
   [junit4]   2> 1632223 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[16 (1573373364072873984)]} 0 0
   [junit4]   2> 1632224 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[17 (1573373364072873984)]} 0 0
   [junit4]   2> 1632224 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[17 (1573373364073922560)]} 0 0
   [junit4]   2> 1632225 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[100 (1573373364073922560)]} 0 0
   [junit4]   2> 1632225 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[100 (1573373364074971136)]} 0 0
   [junit4]   2> 1632225 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[101 (1573373364074971136)]} 0 0
   [junit4]   2> 1632226 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[101 (1573373364076019712)]} 0 0
   [junit4]   2> 1632226 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[102 (1573373364076019712)]} 0 0
   [junit4]   2> 1632227 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[102 (1573373364077068288)]} 0 0
   [junit4]   2> 1632227 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[103 (1573373364077068288)]} 0 0
   [junit4]   2> 1632228 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[103 (1573373364078116864)]} 0 0
   [junit4]   2> 1632229 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[104 (1573373364079165440)]} 0 0
   [junit4]   2> 1632230 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[104 (1573373364079165440)]} 0 0
   [junit4]   2> 1632231 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[105 (1573373364080214016)]} 0 0
   [junit4]   2> 1632231 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[105 (1573373364081262592)]} 0 0
   [junit4]   2> 1632232 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[106 (1573373364082311168)]} 0 0
   [junit4]   2> 1632233 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[106 (1573373364083359744)]} 0 0
   [junit4]   2> 1632233 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[107 (1573373364083359744)]} 0 0
   [junit4]   2> 1632234 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[107 (1573373364084408320)]} 0 0
   [junit4]   2> 1632235 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[108 (1573373364084408320)]} 0 0
   [junit4]   2> 1632235 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[108 (1573373364085456896)]} 0 0
   [junit4]   2> 1632236 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[109 (1573373364086505472)]} 0 0
   [junit4]   2> 1632237 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[109 (1573373364086505472)]} 0 0
   [junit4]   2> 1632238 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[110 (1573373364087554048)]} 0 0
   [junit4]   2> 1632238 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[110 (1573373364088602624)]} 0 0
   [junit4]   2> 1632239 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[111 (1573373364089651200)]} 0 0
   [junit4]   2> 1632240 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[111 (1573373364089651200)]} 0 0
   [junit4]   2> 1632240 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[112 (1573373364090699776)]} 0 0
   [junit4]   2> 1632240 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[112 (1573373364090699776)]} 0 0
   [junit4]   2> 1632240 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[113 (1573373364090699777)]} 0 0
   [junit4]   2> 1632240 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[113 (1573373364090699777)]} 0 0
   [junit4]   2> 1632240 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[114 (1573373364090699778)]} 0 0
   [junit4]   2> 1632244 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[114 (1573373364094894080)]} 0 4
   [junit4]   2> 1632245 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[115 (1573373364095942656)]} 0 0
   [junit4]   2> 1632245 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[115 (1573373364095942656)]} 0 0
   [junit4]   2> 1632246 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[116 (1573373364096991232)]} 0 0
   [junit4]   2> 1632246 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[116 (1573373364096991232)]} 0 0
   [junit4]   2> 1632247 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[117 (1573373364098039808)]} 0 0
   [junit4]   2> 1632247 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[117 (1573373364098039808)]} 0 0
   [junit4]   2> 1632248 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[118 (1573373364099088384)]} 0 0
   [junit4]   2> 1632249 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[118 (1573373364099088384)]} 0 0
   [junit4]   2> 1632249 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[119 (1573373364100136960)]} 0 0
   [junit4]   2> 1632250 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[119 (1573373364101185536)]} 0 0
   [junit4]   2> 1632251 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[120 (1573373364101185536)]} 0 0
   [junit4]   2> 1632251 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[120 (1573373364102234112)]} 0 0
   [junit4]   2> 1632252 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[121 (1573373364103282688)]} 0 0
   [junit4]   2> 1632252 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[121 (1573373364103282688)]} 0 0
   [junit4]   2> 1632253 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[122 (1573373364104331264)]} 0 0
   [junit4]   2> 1632253 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[122 (1573373364104331264)]} 0 0
   [junit4]   2> 1632254 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[123 (1573373364105379840)]} 0 0
   [junit4]   2> 1632255 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[123 (1573373364106428416)]} 0 0
   [junit4]   2> 1632256 INFO  (qtp1564526762-20368) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[124 (1573373364106428416)]} 0 0
   [junit4]   2> 1632256 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[124 (1573373364107476992)]} 0 0
   [junit4]   2> 1632257 INFO  (qtp1564526762-20370) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[125 (1573373364108525568)]} 0 0
   [junit4]   2> 1632259 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[125 (1573373364110622720)]} 0 0
   [junit4]   2> 1632260 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[126 (1573373364111671296)]} 0 0
   [junit4]   2> 1632261 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[126 (1573373364112719872)]} 0 0
   [junit4]   2> 1632262 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[127 (1573373364113768448)]} 0 0
   [junit4]   2> 1632263 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[127 (1573373364113768448)]} 0 0
   [junit4]   2> 1632263 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[128 (1573373364114817024)]} 0 0
   [junit4]   2> 1632264 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[128 (1573373364115865600)]} 0 0
   [junit4]   2> 1632265 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[129 (1573373364116914176)]} 0 0
   [junit4]   2> 1632266 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[129 (1573373364116914176)]} 0 0
   [junit4]   2> 1632266 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[130 (1573373364117962752)]} 0 0
   [junit4]   2> 1632267 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[130 (1573373364119011328)]} 0 0
   [junit4]   2> 1632268 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[131 (1573373364119011328)]} 0 0
   [junit4]   2> 1632268 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[131 (1573373364120059904)]} 0 0
   [junit4]   2> 1632269 INFO  (qtp1564526762-20368) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[132 (1573373364121108480)]} 0 0
   [junit4]   2> 1632270 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[132 (1573373364122157056)]} 0 0
   [junit4]   2> 1632271 INFO  (qtp1564526762-20370) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[133 (1573373364123205632)]} 0 0
   [junit4]   2> 1632272 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[133 (1573373364124254208)]} 0 0
   [junit4]   2> 1632273 INFO  (qtp1564526762-20366) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[134 (1573373364124254208)]} 0 0
   [junit4]   2> 1632273 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[134 (1573373364125302784)]} 0 0
   [junit4]   2> 1632274 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[135 (1573373364126351360)]} 0 0
   [junit4]   2> 1632274 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[135 (1573373364126351360)]} 0 0
   [junit4]   2> 1632275 INFO  (qtp1564526762-20369) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[136 (1573373364127399936)]} 0 0
   [junit4]   2> 1632276 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[136 (1573373364127399936)]} 0 0
   [junit4]   2> 1632276 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[137 (1573373364128448512)]} 0 0
   [junit4]   2> 1632276 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[137 (1573373364128448512)]} 0 0
   [junit4]   2> 1632282 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[138 (1573373364133691392)]} 0 0
   [junit4]   2> 1632282 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[138 (1573373364134739968)]} 0 0
   [junit4]   2> 1632283 INFO  (qtp1564526762-20368) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[139 (1573373364135788544)]} 0 0
   [junit4]   2> 1632284 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[139 (1573373364135788544)]} 0 0
   [junit4]   2> 1632284 INFO  (qtp1564526762-20370) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[140 (1573373364136837120)]} 0 0
   [junit4]   2> 1632285 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[140 (1573373364137885696)]} 0 0
   [junit4]   2> 1632285 INFO  (qtp1564526762-20366) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[141 (1573373364137885696)]} 0 0
   [junit4]   2> 1632286 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[141 (1573373364138934272)]} 0 0
   [junit4]   2> 1632287 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[142 (1573373364138934272)]} 0 0
   [junit4]   2> 1632287 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[142 (1573373364139982848)]} 0 0
   [junit4]   2> 1632287 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[143 (1573373364139982848)]} 0 0
   [junit4]   2> 1632288 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[143 (1573373364141031424)]} 0 0
   [junit4]   2> 1632288 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[144 (1573373364141031424)]} 0 0
   [junit4]   2> 1632289 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[144 (1573373364142080000)]} 0 0
   [junit4]   2> 1632289 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[145 (1573373364142080000)]} 0 0
   [junit4]   2> 1632290 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[145 (1573373364142080001)]} 0 0
   [junit4]   2> 1632290 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[146 (1573373364143128576)]} 0 0
   [junit4]   2> 1632291 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[146 (1573373364143128576)]} 0 0
   [junit4]   2> 1632291 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[147 (1573373364144177152)]} 0 0
   [junit4]   2> 1632291 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[147 (1573373364144177152)]} 0 0
   [junit4]   2> 1632292 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[148 (1573373364145225728)]} 0 0
   [junit4]   2> 1632292 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[148 (1573373364145225728)]} 0 0
   [junit4]   2> 1632292 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[149 (1573373364145225729)]} 0 0
   [junit4]   2> 1632293 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={wt=javabin&version=2}{add=[149 (1573373364146274304)]} 0 0
   [junit4]   2> 1632293 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573373364146274304,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1632293 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@7b41116e 
commitCommandVersion:1573373364146274304
   [junit4]   2> 1632297 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2d39bb0e[collection1] main]
   [junit4]   2> 1632298 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1632299 INFO  
(searcherExecutor-7184-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2d39bb0e[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c9)
 Uninverting(_1(7.1.0):c59)))}
   [junit4]   2> 1632299 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 5
   [junit4]   2> 1632299 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{_version_=1573373364152565760,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1632299 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6f0b2ce1 
commitCommandVersion:1573373364152565760
   [junit4]   2> 1632303 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3a44e46f[collection1] main]
   [junit4]   2> 1632303 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 1632304 INFO  
(searcherExecutor-7195-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3a44e46f[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.1.0):c9)
 Uninverting(_1(7.1.0):c59)))}
   [junit4]   2> 1632304 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/_enk/f path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 4
   [junit4]   2> 1632305 INFO  (qtp1564526762-20369) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1632305 INFO  (qtp1564526762-20369) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*&distrib=false&start=-1&wt=javabin&version=2} status=400 QTime=0
   [junit4]   2> 1632306 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.h.RequestHandlerBase Ignoring exception matching parameter cannot be 
negative
   [junit4]   2> 1632306 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*&distrib=false&start=0&rows=-1&wt=javabin&version=2} status=400 
QTime=0
   [junit4]   2> 1632307 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_ti1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632311 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f 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:64223/_enk/f/collection1&rows=10&version=2&q=*:*&NOW=1500485767519&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632312 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767519&ids=11,100,2,135,3,16,126,116,106,141&isShard=true&shard.url=http://127.0.0.1:64223/_enk/f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632313 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=http://127.0.0.1:64223/_enk/f/collection1&sort=n_ti1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=4
   [junit4]   2> 1632314 INFO  (qtp1564526762-20368) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_ti1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632321 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_ti1+asc&shard.url=[ff01::114]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f|[ff01::083]:33332/_enk/f&rows=10&version=2&q=*:*&NOW=1500485767527&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632322 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767527&ids=101,14,125,103,115,17,139,1001,10,142&isShard=true&shard.url=[ff01::114]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f|[ff01::083]:33332/_enk/f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632324 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=[ff01::114]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f|[ff01::083]:33332/_enk/f&sort=n_ti1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=7
   [junit4]   2> 1632325 INFO  (qtp1564526762-20370) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_f1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632327 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_f1+desc&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::114]:33332/_enk/f&rows=10&version=2&q=*:*&NOW=1500485767537&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632328 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767537&ids=132,100,112,145,147,15,148,137,129,108&isShard=true&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::114]:33332/_enk/f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632329 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=http://127.0.0.1:64223/_enk/f/collection1|[ff01::114]:33332/_enk/f&sort=n_f1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1632330 INFO  (qtp1564526762-20366) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_f1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632336 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f 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/_enk/f|[ff01::114]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&rows=10&version=2&q=*:*&NOW=1500485767542&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632337 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767542&ids=143,133,13,102,16,6,118,140,130,142&isShard=true&shard.url=[ff01::213]:33332/_enk/f|[ff01::114]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632338 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=[ff01::213]:33332/_enk/f|[ff01::114]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&sort=n_f1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 1632339 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_tf1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632341 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+desc&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f&rows=10&version=2&q=*:*&NOW=1500485767551&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632342 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767551&ids=134,13,148,126,137,129,9,109,140,141&isShard=true&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632343 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f&sort=n_tf1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=3
   [junit4]   2> 1632344 INFO  (qtp1564526762-20369) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_tf1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632347 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tf1+asc&shard.url=[ff01::213]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&rows=10&version=2&q=*:*&NOW=1500485767557&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632348 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767557&ids=132,122,101,146,104,105,106,1001,8,108&isShard=true&shard.url=[ff01::213]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632349 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=[ff01::213]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&sort=n_tf1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1632349 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_d1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632351 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_d1+desc&shard.url=http://127.0.0.1:64223/_enk/f/collection1&rows=10&version=2&q=*:*&NOW=1500485767561&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632371 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767561&ids=144,135,124,114,4,125,16,137,1001,142&isShard=true&shard.url=http://127.0.0.1:64223/_enk/f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632371 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=http://127.0.0.1:64223/_enk/f/collection1&sort=n_d1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=21
   [junit4]   2> 1632372 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_d1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632373 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f 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:64223/_enk/f/collection1&rows=10&version=2&q=*:*&NOW=1500485767584&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632374 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767584&ids=11,122,148,17,138,117,106,118,8,108&isShard=true&shard.url=http://127.0.0.1:64223/_enk/f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632375 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=http://127.0.0.1:64223/_enk/f/collection1&sort=n_d1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=1
   [junit4]   2> 1632375 INFO  (qtp1564526762-20368) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_td1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632377 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_td1+desc&shard.url=[ff01::114]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&rows=10&version=2&q=*:*&NOW=1500485767587&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632378 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767587&ids=121,133,2,146,3,103,125,17,129,140&isShard=true&shard.url=[ff01::114]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632378 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=[ff01::114]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&sort=n_td1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1632379 INFO  (qtp1564526762-20370) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_td1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632381 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f 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:64223/_enk/f/collection1|[ff01::083]:33332/_enk/f&rows=10&version=2&q=*:*&NOW=1500485767591&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632382 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767591&ids=1,100,144,14,113,15,137,126,106,118&isShard=true&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::083]:33332/_enk/f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632382 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=http://127.0.0.1:64223/_enk/f/collection1|[ff01::083]:33332/_enk/f&sort=n_td1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1632383 INFO  (qtp1564526762-20366) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_l1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632388 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_l1+desc&shard.url=[ff01::083]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f&rows=10&version=2&q=*:*&NOW=1500485767595&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632390 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767595&ids=145,147,16,115,116,117,9,130,10,131&isShard=true&shard.url=[ff01::083]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632390 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=[ff01::083]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f&sort=n_l1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=6
   [junit4]   2> 1632391 INFO  (qtp1564526762-20372) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_l1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632393 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f 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/_enk/f|http://127.0.0.1:64223/_enk/f/collection1|[ff01::114]:33332/_enk/f|[ff01::213]:33332/_enk/f&rows=10&version=2&q=*:*&NOW=1500485767603&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632394 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767603&ids=133,101,135,114,104,149,127,1001,128,141&isShard=true&shard.url=[ff01::083]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1|[ff01::114]:33332/_enk/f|[ff01::213]:33332/_enk/f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632394 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=[ff01::083]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1|[ff01::114]:33332/_enk/f|[ff01::213]:33332/_enk/f&sort=n_l1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1632395 INFO  (qtp1564526762-20369) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_tl1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632397 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_tl1+desc&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f|[ff01::083]:33332/_enk/f&rows=10&version=2&q=*:*&NOW=1500485767607&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632398 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767607&ids=11,100,122,145,101,5,105,1001,139,118&isShard=true&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f|[ff01::083]:33332/_enk/f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632399 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=http://127.0.0.1:64223/_enk/f/collection1|[ff01::213]:33332/_enk/f|[ff01::083]:33332/_enk/f&sort=n_tl1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1632400 INFO  (qtp1564526762-20373) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_tl1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632402 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f 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:64223/_enk/f/collection1|[ff01::083]:33332/_enk/f|[ff01::213]:33332/_enk/f&rows=10&version=2&q=*:*&NOW=1500485767612&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632403 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767612&ids=132,1,3,135,146,106,128,140,141,131&isShard=true&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::083]:33332/_enk/f|[ff01::213]:33332/_enk/f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632403 INFO  (qtp1531560668-20388) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=http://127.0.0.1:64223/_enk/f/collection1|[ff01::083]:33332/_enk/f|[ff01::213]:33332/_enk/f&sort=n_tl1+asc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1632405 INFO  (qtp1564526762-20371) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_dt1+desc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632406 INFO  (qtp1531560668-20383) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+desc&shard.url=[ff01::114]:33332/_enk/f|[ff01::213]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&rows=10&version=2&q=*:*&NOW=1500485767617&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632407 INFO  (qtp1531560668-20387) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767617&ids=121,111,144,101,17,7,128,109,142,120&isShard=true&shard.url=[ff01::114]:33332/_enk/f|[ff01::213]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632408 INFO  (qtp1531560668-20390) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&shards=[ff01::114]:33332/_enk/f|[ff01::213]:33332/_enk/f|http://127.0.0.1:64223/_enk/f/collection1&sort=n_dt1+desc&wt=javabin&version=2}
 hits=68 status=0 QTime=2
   [junit4]   2> 1632409 INFO  (qtp1564526762-20368) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&distrib=false&sort=n_dt1+asc&wt=javabin&version=2} hits=68 
status=0 QTime=0
   [junit4]   2> 1632410 INFO  (qtp1531560668-20389) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={df=text&distrib=false&fl=id&shards.purpose=4&start=0&fsv=true&sort=n_dt1+asc&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::114]:33332/_enk/f&rows=10&version=2&q=*:*&NOW=1500485767621&isShard=true&wt=javabin}
 hits=68 status=0 QTime=0
   [junit4]   2> 1632411 INFO  (qtp1531560668-20385) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select 
params={q=*:*&df=text&distrib=false&shards.purpose=64&NOW=1500485767621&ids=133,134,2,102,104,116,117,118,129,10&isShard=true&shard.url=http://127.0.0.1:64223/_enk/f/collection1|[ff01::114]:33332/_enk/f&wt=javabin&version=2}
 status=0 QTime=0
   [junit4]   2> 1632411 INFO  (qtp1531560668-20386) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp=/_enk/f path=/select p

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

$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2>        at 
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2>        at 
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
   [junit4]   2>        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 
   [junit4]   2> 1760541 INFO  
(TEST-AutoCommitTest.testCommitWithin-seed#[7591DF3462BA620E]) [    
x:collection1] o.a.s.SolrTestCaseJ4 ###Ending testCommitWithin
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=AutoCommitTest 
-Dtests.method=testCommitWithin -Dtests.seed=7591DF3462BA620E -Dtests.slow=true 
-Dtests.locale=es-UY -Dtests.timezone=America/Nome -Dtests.asserts=true 
-Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.47s J0 | AutoCommitTest.testCommitWithin <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during 
query
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([7591DF3462BA620E:CF43B04CE1948C1B]:0)
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:878)
   [junit4]    >        at 
org.apache.solr.update.AutoCommitTest.testCommitWithin(AutoCommitTest.java:353)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: 
xpath=//result[@numFound=1]
   [junit4]    >        xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]    > <response>
   [junit4]    > <lst name="responseHeader"><int name="status">0</int><int 
name="QTime">0</int></lst><result name="response" numFound="0" 
start="0"></result>
   [junit4]    > </response>
   [junit4]    >        request was:q=id:529&qt=&start=0&rows=20&version=2.2
   [junit4]    >        at 
org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:871)
   [junit4]    >        ... 40 more
   [junit4]   2> 1760541 INFO  
(SUITE-AutoCommitTest-seed#[7591DF3462BA620E]-worker) [    x:collection1] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1760541 INFO  
(SUITE-AutoCommitTest-seed#[7591DF3462BA620E]-worker) [    x:collection1] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=652071768
   [junit4]   2> 1760542 INFO  
(SUITE-AutoCommitTest-seed#[7591DF3462BA620E]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, 
tag=null
   [junit4]   2> 1760543 INFO  
(SUITE-AutoCommitTest-seed#[7591DF3462BA620E]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, 
tag=null
   [junit4]   2> 1760543 INFO  
(SUITE-AutoCommitTest-seed#[7591DF3462BA620E]-worker) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, 
tag=null
   [junit4]   2> 1760543 INFO  
(SUITE-AutoCommitTest-seed#[7591DF3462BA620E]-worker) [    x:collection1] 
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 
transient cores
   [junit4]   2> 1760544 INFO  
(coreCloseExecutor-7919-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@be9c85c
   [junit4]   2> 1760544 INFO  
(coreCloseExecutor-7919-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter close.
   [junit4]   2> 1760544 INFO  
(coreCloseExecutor-7919-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@343b67ab commitCommandVersion:0
   [junit4]   2> 1760545 INFO  
(coreCloseExecutor-7919-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.m.SolrMetricManager Closing metric reporters for 
registry=solr.core.collection1, tag=199870556
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/export/home/jenkins/workspace/Lucene-Solr-7.x-Solaris/solr/build/solr-core/test/J0/temp/solr.update.AutoCommitTest_7591DF3462BA620E-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, 
sim=RandomSimilarity(queryNorm=true): {}, locale=es-UY, timezone=America/Nome
   [junit4]   2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_131 
(64-bit)/cpus=3,threads=1,free=198083312,total=518979584
   [junit4]   2> NOTE: All tests run in this JVM: [RankQueryTest, 
TestFunctionQuery, TestReqParamsAPI, TestCloudJSONFacetJoinDomain, 
TestFieldCacheWithThreads, DistributedQueryComponentCustomSortTest, 
SolrIndexMetricsTest, TestJavabinTupleStreamParser, 
PKIAuthenticationIntegrationTest, TestLMDirichletSimilarityFactory, 
HdfsChaosMonkeySafeLeaderTest, TestPhraseSuggestions, 
TestSizeLimitedDistributedMap, TestConfigSetsAPI, AnalysisErrorHandlingTest, 
RegexBoostProcessorTest, SuggesterWFSTTest, TestFuzzyAnalyzedSuggestions, 
ResponseLogComponentTest, DistributedVersionInfoTest, 
RecoveryAfterSoftCommitTest, TestReversedWildcardFilterFactory, 
TestPrepRecovery, LeaderElectionContextKeyTest, UpdateLogTest, TestOrdValues, 
TestSolrCLIRunExample, ReplaceNodeTest, TestDeleteCollectionOnDownNodes, 
TestAddFieldRealTimeGet, TlogReplayBufferedWhileIndexingTest, 
HardAutoCommitTest, TestLegacyField, TestUninvertingReader, 
DistributedFacetPivotLongTailTest, TestStressLiveNodes, SolrShardReporterTest, 
TestClusterStateMutator, TestXmlQParserPlugin, SpatialFilterTest, 
TestDownShardTolerantSearch, TestExactStatsCache, TestMiniSolrCloudCluster, 
DistributedSuggestComponentTest, TestSolrConfigHandler, 
ShufflingReplicaListTransformerTest, HdfsNNFailoverTest, 
OverseerCollectionConfigSetProcessorTest, SortByFunctionTest, 
SolrMetricsIntegrationTest, XsltUpdateRequestHandlerTest, 
SolrIndexSplitterTest, TestRawResponseWriter, SmileWriterTest, HLLUtilTest, 
TestCloudDeleteByQuery, OverseerTest, TestSlowCompositeReaderWrapper, 
SliceStateTest, PathHierarchyTokenizerFactoryTest, DirectSolrConnectionTest, 
TestNRTOpen, TestClassicSimilarityFactory, TestConfigOverlay, 
RestartWhileUpdatingTest, TestConfigSetProperties, TestFoldingMultitermQuery, 
ConnectionReuseTest, BJQParserTest, TestInfoStreamLogging, 
TestFilteredDocIdSet, FullHLLTest, AddSchemaFieldsUpdateProcessorFactoryTest, 
TestPseudoReturnFields, UnloadDistributedZkTest, CollectionTooManyReplicasTest, 
CoreAdminHandlerTest, DataDrivenBlockJoinTest, CleanupOldIndexTest, 
ForceLeaderTest, TestLocalFSCloudBackupRestore, TestMiniSolrCloudClusterSSL, 
TestMissingGroups, DistanceUnitsTest, GraphQueryTest, TestReplicationHandler, 
NotRequiredUniqueKeyTest, DistributedSpellCheckComponentTest, DocValuesTest, 
TestGeoJSONResponseWriter, VMParamsZkACLAndCredentialsProvidersTest, 
ClassificationUpdateProcessorIntegrationTest, CoreAdminRequestStatusTest, 
TestSolrConfigHandlerCloud, TestSystemCollAutoCreate, 
TestHttpShardHandlerFactory, TestSolrCoreProperties, SyncSliceTest, 
TestLegacyNumericRangeQueryBuilder, TestRandomDVFaceting, 
TestExtendedDismaxParser, DistribJoinFromCollectionTest, 
IgnoreCommitOptimizeUpdateProcessorFactoryTest, 
HdfsWriteToMultipleCollectionsTest, TestSolrCloudWithKerberosAlt, 
ChangedSchemaMergeTest, TestSchemaResource, SimplePostToolTest, 
TestDynamicFieldResource, QueryParsingTest, HighlighterMaxOffsetTest, 
V2StandaloneTest, SharedFSAutoReplicaFailoverUtilsTest, UtilsToolTest, 
TestCopyFieldCollectionResource, TestCoreContainer, DeleteInactiveReplicaTest, 
OverseerRolesTest, RequestHandlersTest, TestBlobHandler, TestStressVersions, 
PreAnalyzedUpdateProcessorTest, TestStandardQParsers, TestDynamicLoading, 
TestCloudPseudoReturnFields, MultiThreadedOCPTest, CSVRequestHandlerTest, 
CircularListTest, TestTrieFacet, SolrSlf4jReporterTest, 
PropertiesRequestHandlerTest, SuggestComponentContextFilterQueryTest, 
TestBinaryField, TestInPlaceUpdatesDistrib, XmlUpdateRequestHandlerTest, 
TestFieldCacheSortRandom, TestRandomRequestDistribution, TestJmxIntegration, 
MissingSegmentRecoveryTest, LeaderInitiatedRecoveryOnShardRestartTest, 
ShardRoutingTest, TestHighlightDedupGrouping, URLClassifyProcessorTest, 
LeaderElectionTest, TestReplicaProperties, 
CloneFieldUpdateProcessorFactoryTest, ZkCLITest, ResourceLoaderTest, 
CachingDirectoryFactoryTest, TestDFRSimilarityFactory, HttpSolrCallGetCoreTest, 
TestRequestStatusCollectionAPI, TestSubQueryTransformer, TestSQLHandler, 
TestLFUCache, SpatialRPTFieldTypeTest, ZkStateWriterTest, NumericFieldsTest, 
TestFieldResource, ClusterStateTest, TestDistributedSearch, 
TestQuerySenderListener, FieldAnalysisRequestHandlerTest, SolrInfoBeanTest, 
BinaryUpdateRequestHandlerTest, TestBulkSchemaAPI, TestMacros, 
TestAnalyzeInfixSuggestions, TestSolrQueryResponse, QueryResultKeyTest, 
DistributedFacetExistsSmallTest, TestCSVResponseWriter, TestNoOpRegenerator, 
TestSchemaVersionResource, ExitableDirectoryReaderTest, TestCodecSupport, 
CollectionReloadTest, TestPKIAuthenticationPlugin, OpenCloseCoreStressTest, 
LeaderElectionIntegrationTest, RecoveryZkTest, AutoCommitTest]
   [junit4] Completed [374/728 (2!)] on J0 in 6.33s, 3 tests, 1 error <<< 
FAILURES!

[...truncated 44582 lines...]

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