Build: https://builds.apache.org/job/Lucene-Solr-Tests-6.x/961/

3 tests failed.
FAILED:  org.apache.solr.search.stats.TestExactSharedStatsCache.test

Error Message:
expected:<2.871802> but was:<2.252763>

Stack Trace:
java.lang.AssertionError: expected:<2.871802> but was:<2.252763>
        at 
__randomizedtesting.SeedInfo.seed([C17A0740D0265A5B:492E389A7EDA37A3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:147)
        at 
org.apache.solr.search.stats.TestBaseStatsCache.checkResponse(TestBaseStatsCache.java:64)
        at 
org.apache.solr.search.stats.TestDefaultStatsCache.dfQuery(TestDefaultStatsCache.java:109)
        at 
org.apache.solr.search.stats.TestDefaultStatsCache.test(TestDefaultStatsCache.java:76)
        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:1018)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        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.search.stats.TestExactStatsCache.test

Error Message:
expected:<2.871802> but was:<2.252763>

Stack Trace:
java.lang.AssertionError: expected:<2.871802> but was:<2.252763>
        at 
__randomizedtesting.SeedInfo.seed([C17A0740D0265A5B:492E389A7EDA37A3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:147)
        at 
org.apache.solr.search.stats.TestBaseStatsCache.checkResponse(TestBaseStatsCache.java:64)
        at 
org.apache.solr.search.stats.TestDefaultStatsCache.dfQuery(TestDefaultStatsCache.java:109)
        at 
org.apache.solr.search.stats.TestDefaultStatsCache.test(TestDefaultStatsCache.java:76)
        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:1018)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        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.search.stats.TestLRUStatsCache.test

Error Message:
expected:<2.871802> but was:<2.252763>

Stack Trace:
java.lang.AssertionError: expected:<2.871802> but was:<2.252763>
        at 
__randomizedtesting.SeedInfo.seed([C17A0740D0265A5B:492E389A7EDA37A3]:0)
        at org.junit.Assert.fail(Assert.java:93)
        at org.junit.Assert.failNotEquals(Assert.java:647)
        at org.junit.Assert.assertEquals(Assert.java:128)
        at org.junit.Assert.assertEquals(Assert.java:147)
        at 
org.apache.solr.search.stats.TestBaseStatsCache.checkResponse(TestBaseStatsCache.java:64)
        at 
org.apache.solr.search.stats.TestDefaultStatsCache.dfQuery(TestDefaultStatsCache.java:109)
        at 
org.apache.solr.search.stats.TestDefaultStatsCache.test(TestDefaultStatsCache.java:76)
        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:1018)
        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
        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)




Build Log:
[...truncated 11881 lines...]
   [junit4] Suite: org.apache.solr.search.stats.TestExactSharedStatsCache
   [junit4]   2> Creating dataDir: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/init-core-data-001
   [junit4]   2> 587816 INFO  
(SUITE-TestExactSharedStatsCache-seed#[C17A0740D0265A5B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Using TrieFields
   [junit4]   2> 587817 INFO  
(SUITE-TestExactSharedStatsCache-seed#[C17A0740D0265A5B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: 
@org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
   [junit4]   2> 587817 INFO  
(SUITE-TestExactSharedStatsCache-seed#[C17A0740D0265A5B]-worker) [    ] 
o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
   [junit4]   2> 587917 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/control/cores/collection1
   [junit4]   2> 587918 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 587919 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@58949274{/,null,AVAILABLE}
   [junit4]   2> 587919 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@1bdc1cfc{HTTP/1.1,[http/1.1]}{127.0.0.1:34869}
   [junit4]   2> 587920 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server Started @590023ms
   [junit4]   2> 587920 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=34869, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/control/cores}
   [junit4]   2> 587920 ERROR 
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 587920 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 587920 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 587920 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 587920 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-25T15:57:57.131Z
   [junit4]   2> 587921 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/control/solr.xml
   [junit4]   2> 587927 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 587944 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/control/cores
   [junit4]   2> 587944 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 587945 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 587971 WARN  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 587972 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 587992 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 588087 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 588092 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 588093 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 588093 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 588093 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b88f05e
   [junit4]   2> 588094 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=31, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=84.8251953125, 
floorSegmentMB=1.5458984375, forceMergeDeletesPctAllowed=21.169223230700823, 
segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 588148 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 588148 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 588149 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 588149 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 588150 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 588150 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@17a8d2b8[collection1] main]
   [junit4]   2> 588151 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 588159 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 588178 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 588178 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 588178 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 4 transient cores
   [junit4]   2> 588179 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 588179 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 588179 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 588179 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 588179 INFO  (coreLoadExecutor-2476-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1571192860720496640
   [junit4]   2> 588180 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 588180 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 588180 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 588180 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 588182 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@17a8d2b8[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 588533 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard0/cores/collection1
   [junit4]   2> 588534 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 588540 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@62fef93a{/,null,AVAILABLE}
   [junit4]   2> 588541 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@5db88ddf{HTTP/1.1,[http/1.1]}{127.0.0.1:49645}
   [junit4]   2> 588541 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server Started @590644ms
   [junit4]   2> 588541 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=49645, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard0/cores}
   [junit4]   2> 588542 ERROR 
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 588542 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 588542 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 588542 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 588542 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-25T15:57:57.753Z
   [junit4]   2> 588542 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 588548 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 588567 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard0/cores
   [junit4]   2> 588567 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 588568 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 588594 WARN  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 588595 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 588615 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 588715 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 588721 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 588721 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 588721 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 588722 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b88f05e
   [junit4]   2> 588723 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=31, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=84.8251953125, 
floorSegmentMB=1.5458984375, forceMergeDeletesPctAllowed=21.169223230700823, 
segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 588765 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 588765 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 588766 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 588766 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 588767 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 588767 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@3d8031fb[collection1] main]
   [junit4]   2> 588767 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 588774 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 588792 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 588792 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 588792 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 4 transient cores
   [junit4]   2> 588793 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 588793 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 588793 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 588793 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 588793 INFO  (coreLoadExecutor-2487-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1571192861364322304
   [junit4]   2> 588794 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 588794 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 588794 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 588795 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 588796 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3d8031fb[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 589075 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 589080 INFO  (qtp673228408-6099) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1571192861662117888)} 0 2
   [junit4]   2> 589098 INFO  (qtp546383521-6116) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{deleteByQuery=*:* (-1571192861682040832)} 0 2
   [junit4]   2> 589102 INFO  (qtp673228408-6100) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[0 (1571192861686235136)]} 0 2
   [junit4]   2> 589105 INFO  (qtp546383521-6117) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[0 (1571192861690429440)]} 0 1
   [junit4]   2> 589107 INFO  (qtp673228408-6101) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 589107 INFO  (qtp673228408-6101) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d882c3c
   [junit4]   2> 589112 INFO  (qtp673228408-6101) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@42c704e3[collection1] main]
   [junit4]   2> 589113 INFO  (qtp673228408-6101) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 589115 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@42c704e3[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):C1)))}
   [junit4]   2> 589115 INFO  (qtp673228408-6101) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 8
   [junit4]   2> 589117 INFO  (qtp546383521-6118) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 589117 INFO  (qtp546383521-6118) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@51160960
   [junit4]   2> 589120 INFO  (qtp546383521-6118) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@3f69b2f7[collection1] main]
   [junit4]   2> 589120 INFO  (qtp546383521-6118) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 589122 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@3f69b2f7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):C1)))}
   [junit4]   2> 589123 INFO  (qtp546383521-6118) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 5
   [junit4]   2> 589127 INFO  (qtp673228408-6102) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_t:one&fl=*,score&wt=javabin&version=2&debugQuery=true} hits=1 
status=0 QTime=2
   [junit4]   2> 589138 INFO  (qtp546383521-6113) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=http://127.0.0.1:49645//collection1&rid=127.0.0.1-collection1-1498406278341-36&version=2&q=a_t:one&requestPurpose=GET_TERM_STATS&NOW=1498406278340&isShard=true&wt=javabin&debugQuery=false}
 status=0 QTime=6
   [junit4]   2> 589141 INFO  (qtp546383521-6116) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&org.apache.solr.stats.termStats=a_t:on,1,1&shard.url=http://127.0.0.1:49645//collection1&rid=127.0.0.1-collection1-1498406278341-36&rows=10&version=2&org.apache.solr.stats.colStats=a_t,1,1,3,3&q=a_t:one&requestPurpose=GET_TOP_IDS,SET_TERM_STATS&NOW=1498406278340&isShard=true&org.apache.solr.stats.terms=a_t:on&wt=javabin&debugQuery=false}
 hits=1 status=0 QTime=0
   [junit4]   2> 589144 INFO  (qtp546383521-6117) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=http://127.0.0.1:49645//collection1&rid=127.0.0.1-collection1-1498406278341-36&version=2&q=a_t:one&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1498406278340&ids=0&isShard=true&wt=javabin&debugQuery=true}
 status=0 QTime=0
   [junit4]   2> 589146 INFO  (qtp546383521-6119) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_t:one&shards=http://127.0.0.1:49645//collection1&fl=*,score&wt=javabin&version=2&debugQuery=true}
 rid=127.0.0.1-collection1-1498406278341-36 hits=1 status=0 QTime=16
   [junit4]   1> ======================= Control Response 
=======================
   [junit4]   1> 
{responseHeader={status=0,QTime=2},response={numFound=1,start=0,maxScore=0.5,docs=[SolrDocument{id=0,
 range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], 
a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, 
timestamp=Sun Jun 25 13:57:58 WGST 2017, _version_=1571192861686235136, 
intDvoDefault=42, 
score=0.5}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0=
   [junit4]   1> 0.5 = weight(a_t:on in 0) [MockConfigurableSimilarity], result 
of:
   [junit4]   1>   0.5 = fieldWeight in 0, product of:
   [junit4]   1>     1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>       1.0 = termFreq=1.0
   [junit4]   1>     1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 
from:
   [junit4]   1>       1.0 = docFreq
   [junit4]   1>       1.0 = docCount
   [junit4]   1>     0.5 = fieldNorm(doc=0)
   [junit4]   1> 
},QParser=LuceneQParser,timing={time=2.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=1.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=1.0}}}}}
   [junit4]   1> 
   [junit4]   1> 
   [junit4]   1> ======================= Shard Response =======================
   [junit4]   1> 
   [junit4]   1> 
{responseHeader={status=0,QTime=16},response={numFound=1,start=0,maxScore=0.5,docs=[SolrDocument{id=0,
 range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], 
a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, 
timestamp=Sun Jun 25 13:57:58 WGST 2017, _version_=1571192861690429440, 
intDvoDefault=42, 
score=0.5}]},debug={track={rid=127.0.0.1-collection1-1498406278341-36,PARSE_QUERY={http://127.0.0.1:49645//collection1={QTime=6,ElapsedTime=7,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=6},org.apache.solr.stats.terms=a_t:on,org.apache.solr.stats.termStats=a_t:on,1,1,org.apache.solr.stats.colStats=a_t,1,1,3,3,debug={timing={time=5.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=5.0,query={time=5.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},EXECUTE_QUERY={http://127.0.0.1:49645//collection1={QTime=0,ElapsedTime=2,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=1,Response={responseHeader={status=0,QTime=0},response={numFound=1,start=0,maxScore=0.5,docs=[SolrDocument{id=0,
 
score=0.5}]},sort_values={},debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},GET_FIELDS={http://127.0.0.1:49645//collection1={QTime=0,ElapsedTime=2,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=1,Response={responseHeader={status=0,QTime=0},response={numFound=1,start=0,docs=[SolrDocument{id=0,
 range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], 
a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, 
timestamp=Sun Jun 25 13:57:58 WGST 2017, _version_=1571192861690429440, 
intDvoDefault=42, 
score=0.5}]},debug={rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,explain={0=
   [junit4]   1> 0.5 = weight(a_t:on in 0) [MockConfigurableSimilarity], result 
of:
   [junit4]   1>   0.5 = fieldWeight in 0, product of:
   [junit4]   1>     1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>       1.0 = termFreq=1.0
   [junit4]   1>     1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 
from:
   [junit4]   1>       1.0 = docFreq
   [junit4]   1>       1.0 = docCount
   [junit4]   1>     0.5 = fieldNorm(doc=0)
   [junit4]   1> 
},QParser=LuceneQParser,timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}}},timing={time=5.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=5.0,query={time=5.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}},rawquerystring=a_t:one,querystring=a_t:one,parsedquery=a_t:on,parsedquery_toString=a_t:on,QParser=LuceneQParser,explain={0=
   [junit4]   1> 0.5 = weight(a_t:on in 0) [MockConfigurableSimilarity], result 
of:
   [junit4]   1>   0.5 = fieldWeight in 0, product of:
   [junit4]   1>     1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>       1.0 = termFreq=1.0
   [junit4]   1>     1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 1 
from:
   [junit4]   1>       1.0 = docFreq
   [junit4]   1>       1.0 = docCount
   [junit4]   1>     0.5 = fieldNorm(doc=0)
   [junit4]   1> }}}
   [junit4]   2> 589151 INFO  (qtp673228408-6103) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1571192861737615360)]} 0 2
   [junit4]   2> 589154 INFO  (qtp546383521-6120) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={wt=javabin&version=2}{add=[1 (1571192861741809664)]} 0 1
   [junit4]   2> 589156 INFO  (qtp673228408-6096) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 589156 INFO  (qtp673228408-6096) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@6d882c3c
   [junit4]   2> 589159 INFO  (qtp673228408-6096) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@b74fad7[collection1] main]
   [junit4]   2> 589160 INFO  (qtp673228408-6096) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 589161 INFO  
(searcherExecutor-2477-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@b74fad7[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):C1)
 Uninverting(_1(6.7.0):C1)))}
   [junit4]   2> 589161 INFO  (qtp673228408-6096) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 5
   [junit4]   2> 589163 INFO  (qtp546383521-6114) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 start 
commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 589163 INFO  (qtp546383521-6114) [    x:collection1] 
o.a.s.u.SolrIndexWriter Calling setCommitData with 
IW:org.apache.solr.update.SolrIndexWriter@51160960
   [junit4]   2> 589166 INFO  (qtp546383521-6114) [    x:collection1] 
o.a.s.s.SolrIndexSearcher Opening [Searcher@2fc29647[collection1] main]
   [junit4]   2> 589167 INFO  (qtp546383521-6114) [    x:collection1] 
o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 589168 INFO  
(searcherExecutor-2488-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@2fc29647[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.7.0):C1)
 Uninverting(_1(6.7.0):C1)))}
   [junit4]   2> 589168 INFO  (qtp546383521-6114) [    x:collection1] 
o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp= path=/update 
params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=}
 0 5
   [junit4]   2> 589171 INFO  (qtp673228408-6099) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_t:one+a_t:four&fl=*,score&wt=javabin&version=2&debugQuery=true} 
hits=2 status=0 QTime=1
   [junit4]   2> 589175 INFO  (qtp546383521-6118) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=*,score&shards.purpose=32768&shard.url=http://127.0.0.1:49645//collection1&rid=127.0.0.1-collection1-1498406278384-37&version=2&q=a_t:one+a_t:four&requestPurpose=GET_TERM_STATS&NOW=1498406278384&isShard=true&wt=javabin&debugQuery=false}
 status=0 QTime=0
   [junit4]   2> 589178 INFO  (qtp546383521-6117) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&debug=false&debug=timing&debug=track&fl=id&fl=score&shards.purpose=16388&start=0&fsv=true&org.apache.solr.stats.termStats=a_t:on,2,2!a_t:four,1,1&shard.url=http://127.0.0.1:49645//collection1&rid=127.0.0.1-collection1-1498406278384-37&rows=10&version=2&org.apache.solr.stats.colStats=a_t,2,2,8,8&q=a_t:one+a_t:four&requestPurpose=GET_TOP_IDS,SET_TERM_STATS&NOW=1498406278384&isShard=true&org.apache.solr.stats.terms=a_t:on&org.apache.solr.stats.terms=a_t:four&wt=javabin&debugQuery=false}
 hits=2 status=0 QTime=0
   [junit4]   2> 589180 INFO  (qtp546383521-6119) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={df=text&distrib=false&debug=timing&debug=track&fl=*,score&shards.purpose=320&shard.url=http://127.0.0.1:49645//collection1&rid=127.0.0.1-collection1-1498406278384-37&version=2&q=a_t:one+a_t:four&requestPurpose=GET_FIELDS,GET_DEBUG&NOW=1498406278384&ids=0,1&isShard=true&wt=javabin&debugQuery=true}
 status=0 QTime=0
   [junit4]   2> 589182 INFO  (qtp546383521-6113) [    x:collection1] 
o.a.s.c.S.Request [collection1]  webapp= path=/select 
params={q=a_t:one+a_t:four&shards=http://127.0.0.1:49645//collection1&fl=*,score&wt=javabin&version=2&debugQuery=true}
 rid=127.0.0.1-collection1-1498406278384-37 hits=2 status=0 QTime=8
   [junit4]   1> ======================= Control Response 
=======================
   [junit4]   1> 
{responseHeader={status=0,QTime=1},response={numFound=2,start=0,maxScore=0.75465035,docs=[SolrDocument{id=1,
 range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], 
a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default], 
intDefault=42, timestamp=Sun Jun 25 13:57:58 WGST 2017, 
_version_=1571192861737615360, intDvoDefault=42, score=0.75465035}, 
SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], 
range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], 
multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Jun 25 13:57:58 WGST 
2017, _version_=1571192861686235136, intDvoDefault=42, 
score=0.14493467}]},debug={rawquerystring=a_t:one a_t:four,querystring=a_t:one 
a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on 
a_t:four,explain={1=
   [junit4]   1> 0.75465035 = sum of:
   [junit4]   1>   0.25363567 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.25363567 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.5797387 = queryWeight, product of:
   [junit4]   1>         1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 
1 from:
   [junit4]   1>           2.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.5797387 = queryNorm
   [junit4]   1>       0.4375 = fieldWeight in 0, product of:
   [junit4]   1>         1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>           1.0 = termFreq=1.0
   [junit4]   1>         1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 
1 from:
   [junit4]   1>           2.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.4375 = fieldNorm(doc=0)
   [junit4]   1>   0.5010147 = weight(a_t:four in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.5010147 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.81480247 = queryWeight, product of:
   [junit4]   1>         1.4054651 = idf, computed as 
log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>           1.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.5797387 = queryNorm
   [junit4]   1>       0.614891 = fieldWeight in 0, product of:
   [junit4]   1>         1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>           1.0 = termFreq=1.0
   [junit4]   1>         1.4054651 = idf, computed as 
log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>           1.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.4375 = fieldNorm(doc=0)
   [junit4]   1> ,0=
   [junit4]   1> 0.14493467 = product of:
   [junit4]   1>   0.28986934 = sum of:
   [junit4]   1>     0.28986934 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>       0.28986934 = score(doc=0,freq=1.0), product of:
   [junit4]   1>         0.5797387 = queryWeight, product of:
   [junit4]   1>           1.0 = idf, computed as log((docCount+1)/(docFreq+1)) 
+ 1 from:
   [junit4]   1>             2.0 = docFreq
   [junit4]   1>             2.0 = docCount
   [junit4]   1>           0.5797387 = queryNorm
   [junit4]   1>         0.5 = fieldWeight in 0, product of:
   [junit4]   1>           1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>             1.0 = termFreq=1.0
   [junit4]   1>           1.0 = idf, computed as log((docCount+1)/(docFreq+1)) 
+ 1 from:
   [junit4]   1>             2.0 = docFreq
   [junit4]   1>             2.0 = docCount
   [junit4]   1>           0.5 = fieldNorm(doc=0)
   [junit4]   1>   0.5 = coord(1/2)
   [junit4]   1> 
},QParser=LuceneQParser,timing={time=1.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}
   [junit4]   1> 
   [junit4]   1> 
   [junit4]   1> ======================= Shard Response =======================
   [junit4]   1> 
   [junit4]   1> 
{responseHeader={status=0,QTime=8},response={numFound=2,start=0,maxScore=0.75465035,docs=[SolrDocument{id=1,
 range_facet_l=[1], id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], 
a_t=[one two three four five], shard_i=[1], multiDefault=[muLti-Default], 
intDefault=42, timestamp=Sun Jun 25 13:57:58 WGST 2017, 
_version_=1571192861741809664, intDvoDefault=42, score=0.75465035}, 
SolrDocument{id=0, range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], 
range_facet_i_dv=[0], a_t=[one two three], shard_i=[1], 
multiDefault=[muLti-Default], intDefault=42, timestamp=Sun Jun 25 13:57:58 WGST 
2017, _version_=1571192861690429440, intDvoDefault=42, 
score=0.14493467}]},debug={track={rid=127.0.0.1-collection1-1498406278384-37,PARSE_QUERY={http://127.0.0.1:49645//collection1={QTime=0,ElapsedTime=1,RequestPurpose=GET_TERM_STATS,Response={responseHeader={status=0,QTime=0},org.apache.solr.stats.terms=a_t:four,org.apache.solr.stats.terms=a_t:on,org.apache.solr.stats.termStats=a_t:on,2,2!a_t:four,1,1,org.apache.solr.stats.colStats=a_t,2,2,8,8,debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},EXECUTE_QUERY={http://127.0.0.1:49645//collection1={QTime=0,ElapsedTime=2,RequestPurpose=GET_TOP_IDS,SET_TERM_STATS,NumFound=2,Response={responseHeader={status=0,QTime=0},response={numFound=2,start=0,maxScore=0.75465035,docs=[SolrDocument{id=1,
 score=0.75465035}, SolrDocument{id=0, 
score=0.14493467}]},sort_values={},debug={timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}},GET_FIELDS={http://127.0.0.1:49645//collection1={QTime=0,ElapsedTime=3,RequestPurpose=GET_FIELDS,GET_DEBUG,NumFound=2,Response={responseHeader={status=0,QTime=0},response={numFound=2,start=0,docs=[SolrDocument{id=0,
 range_facet_l=[0], id_i1=0, range_facet_l_dv=[0], range_facet_i_dv=[0], 
a_t=[one two three], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, 
timestamp=Sun Jun 25 13:57:58 WGST 2017, _version_=1571192861690429440, 
intDvoDefault=42, score=0.14493467}, SolrDocument{id=1, range_facet_l=[1], 
id_i1=1, range_facet_l_dv=[1], range_facet_i_dv=[1], a_t=[one two three four 
five], shard_i=[1], multiDefault=[muLti-Default], intDefault=42, timestamp=Sun 
Jun 25 13:57:58 WGST 2017, _version_=1571192861741809664, intDvoDefault=42, 
score=0.75465035}]},debug={rawquerystring=a_t:one a_t:four,querystring=a_t:one 
a_t:four,parsedquery=a_t:on a_t:four,parsedquery_toString=a_t:on 
a_t:four,explain={0=
   [junit4]   1> 0.14493467 = product of:
   [junit4]   1>   0.28986934 = sum of:
   [junit4]   1>     0.28986934 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>       0.28986934 = score(doc=0,freq=1.0), product of:
   [junit4]   1>         0.5797387 = queryWeight, product of:
   [junit4]   1>           1.0 = idf, computed as log((docCount+1)/(docFreq+1)) 
+ 1 from:
   [junit4]   1>             2.0 = docFreq
   [junit4]   1>             2.0 = docCount
   [junit4]   1>           0.5797387 = queryNorm
   [junit4]   1>         0.5 = fieldWeight in 0, product of:
   [junit4]   1>           1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>             1.0 = termFreq=1.0
   [junit4]   1>           1.0 = idf, computed as log((docCount+1)/(docFreq+1)) 
+ 1 from:
   [junit4]   1>             2.0 = docFreq
   [junit4]   1>             2.0 = docCount
   [junit4]   1>           0.5 = fieldNorm(doc=0)
   [junit4]   1>   0.5 = coord(1/2)
   [junit4]   1> ,1=
   [junit4]   1> 0.75465035 = sum of:
   [junit4]   1>   0.25363567 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.25363567 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.5797387 = queryWeight, product of:
   [junit4]   1>         1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 
1 from:
   [junit4]   1>           2.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.5797387 = queryNorm
   [junit4]   1>       0.4375 = fieldWeight in 0, product of:
   [junit4]   1>         1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>           1.0 = termFreq=1.0
   [junit4]   1>         1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 
1 from:
   [junit4]   1>           2.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.4375 = fieldNorm(doc=0)
   [junit4]   1>   0.5010147 = weight(a_t:four in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.5010147 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.81480247 = queryWeight, product of:
   [junit4]   1>         1.4054651 = idf, computed as 
log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>           1.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.5797387 = queryNorm
   [junit4]   1>       0.614891 = fieldWeight in 0, product of:
   [junit4]   1>         1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>           1.0 = termFreq=1.0
   [junit4]   1>         1.4054651 = idf, computed as 
log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>           1.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.4375 = fieldNorm(doc=0)
   [junit4]   1> 
},QParser=LuceneQParser,timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}}}}}}},timing={time=0.0,prepare={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}},process={time=0.0,query={time=0.0},facet={time=0.0},facet_module={time=0.0},mlt={time=0.0},highlight={time=0.0},stats={time=0.0},expand={time=0.0},terms={time=0.0},debug={time=0.0}}},rawquerystring=a_t:one
 a_t:four,querystring=a_t:one a_t:four,parsedquery=a_t:on 
a_t:four,parsedquery_toString=a_t:on a_t:four,QParser=LuceneQParser,explain={1=
   [junit4]   1> 0.75465035 = sum of:
   [junit4]   1>   0.25363567 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.25363567 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.5797387 = queryWeight, product of:
   [junit4]   1>         1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 
1 from:
   [junit4]   1>           2.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.5797387 = queryNorm
   [junit4]   1>       0.4375 = fieldWeight in 0, product of:
   [junit4]   1>         1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>           1.0 = termFreq=1.0
   [junit4]   1>         1.0 = idf, computed as log((docCount+1)/(docFreq+1)) + 
1 from:
   [junit4]   1>           2.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.4375 = fieldNorm(doc=0)
   [junit4]   1>   0.5010147 = weight(a_t:four in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>     0.5010147 = score(doc=0,freq=1.0), product of:
   [junit4]   1>       0.81480247 = queryWeight, product of:
   [junit4]   1>         1.4054651 = idf, computed as 
log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>           1.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.5797387 = queryNorm
   [junit4]   1>       0.614891 = fieldWeight in 0, product of:
   [junit4]   1>         1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>           1.0 = termFreq=1.0
   [junit4]   1>         1.4054651 = idf, computed as 
log((docCount+1)/(docFreq+1)) + 1 from:
   [junit4]   1>           1.0 = docFreq
   [junit4]   1>           2.0 = docCount
   [junit4]   1>         0.4375 = fieldNorm(doc=0)
   [junit4]   1> ,0=
   [junit4]   1> 0.14493467 = product of:
   [junit4]   1>   0.28986934 = sum of:
   [junit4]   1>     0.28986934 = weight(a_t:on in 0) 
[MockConfigurableSimilarity], result of:
   [junit4]   1>       0.28986934 = score(doc=0,freq=1.0), product of:
   [junit4]   1>         0.5797387 = queryWeight, product of:
   [junit4]   1>           1.0 = idf, computed as log((docCount+1)/(docFreq+1)) 
+ 1 from:
   [junit4]   1>             2.0 = docFreq
   [junit4]   1>             2.0 = docCount
   [junit4]   1>           0.5797387 = queryNorm
   [junit4]   1>         0.5 = fieldWeight in 0, product of:
   [junit4]   1>           1.0 = tf(freq=1.0), with freq of:
   [junit4]   1>             1.0 = termFreq=1.0
   [junit4]   1>           1.0 = idf, computed as log((docCount+1)/(docFreq+1)) 
+ 1 from:
   [junit4]   1>             2.0 = docFreq
   [junit4]   1>             2.0 = docCount
   [junit4]   1>           0.5 = fieldNorm(doc=0)
   [junit4]   1>   0.5 = coord(1/2)
   [junit4]   1> }}}
   [junit4]   2> 589183 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 589184 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@1bdc1cfc{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 589184 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1372784560
   [junit4]   2> 589184 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 589184 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 589184 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 589185 INFO  (coreCloseExecutor-2493-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@1b8dbb2
   [junit4]   2> 589215 INFO  (coreCloseExecutor-2493-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 589216 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@58949274{/,null,UNAVAILABLE}
   [junit4]   2> 589218 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@5db88ddf{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 589219 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=199604914
   [junit4]   2> 589219 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 589219 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 589219 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 589220 INFO  (coreCloseExecutor-2495-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@7172f652
   [junit4]   2> 589238 INFO  (coreCloseExecutor-2495-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 589239 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@62fef93a{/,null,UNAVAILABLE}
   [junit4]   2> 589335 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 589336 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@20854ce7{/,null,AVAILABLE}
   [junit4]   2> 589336 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@8d6b94c{HTTP/1.1,[http/1.1]}{127.0.0.1:39812}
   [junit4]   2> 589336 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server Started @591440ms
   [junit4]   2> 589336 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=39812, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/control/cores}
   [junit4]   2> 589337 ERROR 
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 589337 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 589337 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 589337 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 589337 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-25T15:57:58.548Z
   [junit4]   2> 589337 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/control/solr.xml
   [junit4]   2> 589343 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 589366 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/control/cores
   [junit4]   2> 589366 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 589367 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 589399 WARN  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 589400 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 589432 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 589517 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 589523 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 589523 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 589523 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/control/cores/collection1/data/]
   [junit4]   2> 589523 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b88f05e
   [junit4]   2> 589524 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=31, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=84.8251953125, 
floorSegmentMB=1.5458984375, forceMergeDeletesPctAllowed=21.169223230700823, 
segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 589564 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 589564 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 589564 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 589564 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 589565 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 589565 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@30e561ea[collection1] main]
   [junit4]   2> 589565 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 589566 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 589568 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 589568 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 589568 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 4 transient cores
   [junit4]   2> 589568 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 589568 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 589569 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 589569 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 589569 INFO  (coreLoadExecutor-2502-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1571192862178017280
   [junit4]   2> 589569 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 589569 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 589570 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 589570 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 589571 INFO  
(searcherExecutor-2503-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@30e561ea[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 589989 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 589990 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@3f9b5cb9{/,null,AVAILABLE}
   [junit4]   2> 589991 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@89d0ea9{HTTP/1.1,[http/1.1]}{127.0.0.1:37748}
   [junit4]   2> 589991 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server Started @592094ms
   [junit4]   2> 589994 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=37748, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard0/cores}
   [junit4]   2> 589994 ERROR 
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 589995 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 589995 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 589995 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 589995 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-25T15:57:59.206Z
   [junit4]   2> 589995 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard0/solr.xml
   [junit4]   2> 590001 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 590026 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard0/cores
   [junit4]   2> 590026 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 590027 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, 
from paths: 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib,
 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 590056 WARN  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is 
deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 590058 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.7.0
   [junit4]   2> 590078 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 590174 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 590180 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using 
configuration from configset 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1,
 trusted=true
   [junit4]   2> 590181 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
   [junit4]   2> 590181 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at 
[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/core/src/test-files/solr/collection1],
 
dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard0/cores/collection1/data/]
   [junit4]   2> 590192 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr 
mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@3b88f05e
   [junit4]   2> 590193 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: 
maxMergeAtOnce=31, maxMergeAtOnceExplicit=18, maxMergedSegmentMB=84.8251953125, 
floorSegmentMB=1.5458984375, forceMergeDeletesPctAllowed=21.169223230700823, 
segmentsPerTier=44.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 590235 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: 
org.apache.solr.update.UpdateLog
   [junit4]   2> 590235 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= 
defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 
numVersionBuckets=65536
   [junit4]   2> 590236 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 590236 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 590236 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class 
org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: 
minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, 
maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, 
maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, 
noCFSRatio=1.0]
   [junit4]   2> 590237 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.s.SolrIndexSearcher Opening 
[Searcher@128a51d0[collection1] main]
   [junit4]   2> 590237 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 590237 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.s.DirectSolrSpellChecker init: 
{name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
   [junit4]   2> 590239 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 590239 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 590240 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache 
for 4 transient cores
   [junit4]   2> 590240 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 590240 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 590240 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: 
multipleFields
   [junit4]   2> 590240 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 590240 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 590240 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 590240 INFO  (coreLoadExecutor-2513-thread-1) [    
x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent 
updates, using new clock 1571192862881611776
   [junit4]   2> 590241 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 590241 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 590242 INFO  
(searcherExecutor-2514-thread-1-processing-x:collection1) [    x:collection1] 
o.a.s.c.SolrCore [collection1] Registered new searcher 
Searcher@128a51d0[collection1] 
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 590630 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.SolrTestCaseJ4 Writing core.properties file to 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard1/cores/collection1
   [junit4]   2> 590631 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server jetty-9.3.14.v20161028
   [junit4]   2> 590633 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Started 
o.e.j.s.ServletContextHandler@10923bf8{/,null,AVAILABLE}
   [junit4]   2> 590633 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Started 
ServerConnector@67040700{HTTP/1.1,[http/1.1]}{127.0.0.1:59200}
   [junit4]   2> 590633 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.Server Started @592737ms
   [junit4]   2> 590633 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, hostPort=59200, 
coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard1/cores}
   [junit4]   2> 590633 ERROR 
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be 
missing or incomplete.
   [junit4]   2> 590634 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 
6.7.0
   [junit4]   2> 590634 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on 
port null
   [junit4]   2> 590634 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 590634 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 
2017-06-25T15:57:59.845Z
   [junit4]   2> 590635 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.SolrXmlConfig Loading container configuration from 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard1/solr.xml
   [junit4]   2> 590641 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: 
socketTimeout=340000&connTimeout=45000&retry=true
   [junit4]   2> 590659 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J1/temp/solr.search.stats.TestExactSharedStatsCache_C17A0740D0265A5B-001/tempDir-001/shard1/cores
   [junit4]   2> 590660 INFO  
(TEST-TestExactSharedStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [j

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

eed#[C17A0740D0265A5B]) [    ] o.a.s.m.SolrMetricManager Closing metric 
reporters for: solr.node
   [junit4]   2> 783345 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 783345 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 783356 INFO  (coreCloseExecutor-4050-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@3a32f323
   [junit4]   2> 783484 INFO  (coreCloseExecutor-4050-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 783485 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@1926beba{/,null,UNAVAILABLE}
   [junit4]   2> 783494 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@1b08b93c{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 783494 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1191193138
   [junit4]   2> 783494 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 783495 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 783495 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 783500 INFO  (coreCloseExecutor-4052-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@53779ad5
   [junit4]   2> 783585 INFO  (coreCloseExecutor-4052-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 783587 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@426f72d0{/,null,UNAVAILABLE}
   [junit4]   2> 783594 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@ed7beca{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 783595 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=902803349
   [junit4]   2> 783595 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 783595 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 783595 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 783612 INFO  (coreCloseExecutor-4054-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@6895d942
   [junit4]   2> 783680 INFO  (coreCloseExecutor-4054-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 783681 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@2760fcc1{/,null,UNAVAILABLE}
   [junit4]   2> 783691 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.AbstractConnector Stopped 
ServerConnector@4fadf4f8{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 783691 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.c.CoreContainer Shutting down CoreContainer instance=1620695425
   [junit4]   2> 783691 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> 783691 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jvm
   [junit4]   2> 783691 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.a.s.m.SolrMetricManager Closing metric reporters for: solr.jetty
   [junit4]   2> 783718 INFO  (coreCloseExecutor-4056-thread-1) [    
x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore 
org.apache.solr.core.SolrCore@2d7c545e
   [junit4]   2> 783789 INFO  (coreCloseExecutor-4056-thread-1) [    
x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: 
solr.core.collection1
   [junit4]   2> 783794 INFO  
(TEST-TestExactStatsCache.test-seed#[C17A0740D0265A5B]) [    ] 
o.e.j.s.h.ContextHandler Stopped 
o.e.j.s.ServletContextHandler@140cc4d9{/,null,UNAVAILABLE}
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestExactStatsCache 
-Dtests.method=test -Dtests.seed=C17A0740D0265A5B -Dtests.multiplier=2 
-Dtests.slow=true -Dtests.locale=de-DE -Dtests.timezone=America/Curacao 
-Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 7.40s J2 | TestExactStatsCache.test <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<2.871802> 
but was:<2.252763>
   [junit4]    >        at 
__randomizedtesting.SeedInfo.seed([C17A0740D0265A5B:492E389A7EDA37A3]:0)
   [junit4]    >        at 
org.apache.solr.search.stats.TestBaseStatsCache.checkResponse(TestBaseStatsCache.java:64)
   [junit4]    >        at 
org.apache.solr.search.stats.TestDefaultStatsCache.dfQuery(TestDefaultStatsCache.java:109)
   [junit4]    >        at 
org.apache.solr.search.stats.TestDefaultStatsCache.test(TestDefaultStatsCache.java:76)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsRepeatStatement.callStatement(BaseDistributedSearchTestCase.java:1018)
   [junit4]    >        at 
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967)
   [junit4]    >        at java.lang.Thread.run(Thread.java:748)
   [junit4]   2> 783797 INFO  
(SUITE-TestExactStatsCache-seed#[C17A0740D0265A5B]-worker) [    ] 
o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> NOTE: leaving temporary files on disk at: 
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-6.x/solr/build/solr-core/test/J2/temp/solr.search.stats.TestExactStatsCache_C17A0740D0265A5B-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene62): 
{multiDefault=PostingsFormat(name=LuceneFixedGap), 
a_t=PostingsFormat(name=Asserting), 
shard_i=PostingsFormat(name=LuceneFixedGap), 
id_i1=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=Direct), 
text=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), 
_version_=DocValuesFormat(name=Memory), 
intDefault=DocValuesFormat(name=Memory), 
range_facet_i_dv=DocValuesFormat(name=Memory), 
intDvoDefault=DocValuesFormat(name=Asserting), 
range_facet_l=DocValuesFormat(name=Memory), 
timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=347, 
maxMBSortInHeap=7.932521766570498, 
sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=de-DE, 
timezone=America/Curacao
   [junit4]   2> NOTE: Linux 3.13.0-88-generic amd64/Oracle Corporation 
1.8.0_131 (64-bit)/cpus=4,threads=1,free=79604832,total=529006592
   [junit4]   2> NOTE: All tests run in this JVM: 
[AddSchemaFieldsUpdateProcessorFactoryTest, TestTrie, TestMiniSolrCloudCluster, 
JsonLoaderTest, DistributedExpandComponentTest, SmileWriterTest, 
TestSolrQueryParser, TestCloudInspectUtil, AlternateDirectoryTest, 
TestFileDictionaryLookup, ShowFileRequestHandlerTest, 
TestDistribDocBasedVersion, CdcrReplicationDistributedZkTest, 
SolrTestCaseJ4Test, DeleteShardTest, TestHdfsCloudBackupRestore, 
SortByFunctionTest, MultiThreadedOCPTest, BasicAuthStandaloneTest, 
TestMacroExpander, SolrJmxReporterTest, OpenExchangeRatesOrgProviderTest, 
ClassificationUpdateProcessorFactoryTest, NotRequiredUniqueKeyTest, 
TestSolrXml, TestCloudPseudoReturnFields, MinimalSchemaTest, 
CloneFieldUpdateProcessorFactoryTest, PolyFieldTest, 
SpellCheckCollatorWithCollapseTest, TestFieldCache, PeerSyncReplicationTest, 
TestSchemaVersionResource, LeaderElectionTest, TestShortCircuitedRequests, 
TestComponentsName, TestFieldCacheWithThreads, DateRangeFieldTest, 
TestGroupingSearch, TestDFRSimilarityFactory, CoreMergeIndexesAdminHandlerTest, 
TestSimpleQParserPlugin, TestTolerantUpdateProcessorRandomCloud, 
FileBasedSpellCheckerTest, HttpPartitionTest, TestSolrConfigHandlerCloud, 
TestQueryUtils, TestCloudSchemaless, TestFastLRUCache, 
AsyncCallRequestStatusResponseTest, TestPayloadCheckQParserPlugin, 
TestOnReconnectListenerSupport, SimplePostToolTest, TestUnifiedSolrHighlighter, 
BJQParserTest, HdfsDirectoryTest, TestFilteredDocIdSet, 
TermVectorComponentDistributedTest, TestCodecSupport, 
DistributedSuggestComponentTest, TestSolrQueryParserResource, 
TestTolerantSearch, TestUseDocValuesAsStored, MergeStrategyTest, 
TestLeaderInitiatedRecoveryThread, ReplaceNodeTest, 
LeaderFailureAfterFreshStartTest, TestMinMaxOnMultiValuedField, 
TestPseudoReturnFields, CreateCollectionCleanupTest, TestBulkSchemaConcurrent, 
ZkStateWriterTest, DistributedVersionInfoTest, TestTrackingShardHandlerFactory, 
TestCorePropertiesReload, TestAnalyzedSuggestions, MultiTermTest, 
TestSolrCoreParser, BooleanFieldTest, TestSubQueryTransformer, 
DirectUpdateHandlerOptimizeTest, UUIDUpdateProcessorFallbackTest, 
TestBulkSchemaAPI, HLLSerializationTest, TestPointFields, 
TestSolrCLIRunExample, DistributedFacetPivotWhiteBoxTest, TestJsonRequest, 
OverseerTest, TestSolrDeletionPolicy2, RollingRestartTest, 
TestStressUserVersions, TestSimpleTextCodec, TestComplexPhraseQParserPlugin, 
XmlUpdateRequestHandlerTest, TestMultiWordSynonyms, TestExactStatsCache]
   [junit4] Completed [329/713 (3!)] on J2 in 7.42s, 1 test, 1 failure <<< 
FAILURES!

[...truncated 44737 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org
For additional commands, e-mail: dev-h...@lucene.apache.org

Reply via email to