You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/12/28 06:05:23 UTC

[JENKINS] Lucene-Solr-Tests-5.x-Java7 - Build # 3885 - Failure

Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/3885/

1 tests failed.
FAILED:  org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([37BF9ADC1981D209:60424E9BCBEC2D9]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:749)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716)
	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:562)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:606)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
	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:46)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	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:54)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 6 <= . and . <= 10]
	xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">5</int></lst><result name="response" numFound="0" start="0"></result><lst name="spellcheck"><lst name="suggestions"><lst name="everother"><int name="numFound">1</int><int name="startOffset">9</int><int name="endOffset">18</int><arr name="suggestion"><str>everyother</str></arr></lst></lst><lst name="collations"><lst name="collation"><str name="collationQuery">teststop:everyother</str><int name="hits">12</int><lst name="misspellingsAndCorrections"><str name="everother">everyother</str></lst></lst></lst></lst>
</response>

	request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:742)
	... 41 more




Build Log:
[...truncated 9808 lines...]
   [junit4] Suite: org.apache.solr.spelling.SpellCheckCollatorTest
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001
   [junit4]   2> 25188 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false)
   [junit4]   2> 25189 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 25189 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 25189 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 25189 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr
   [junit4]   2> 25190 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
   [junit4]   2> 25190 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 25190 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 25221 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 25238 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 25272 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-spellcheckcomponent.xml
   [junit4]   2> 25284 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 25571 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 25581 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 25583 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 25596 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 25599 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 25601 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 25602 WARN  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 25602 WARN  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 25602 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 25602 WARN  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 25602 WARN  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 25603 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 25603 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr
   [junit4]   2> 25603 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr'
   [junit4]   2> 25603 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 25603 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr
   [junit4]   2> 25603 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.CoreContainer New CoreContainer 1521495105
   [junit4]   2> 25603 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr]
   [junit4]   2> 25604 WARN  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/lib
   [junit4]   2> 25604 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 600000,connTimeout : 60000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
   [junit4]   2> 25610 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 25613 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 25613 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 25613 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 25613 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 25615 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig-spellcheckcomponent.xml, configSetProperties=configsetprops.json, dataDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001, schema=schema.xml, collection=collection1, shard=shard1, transient=false, loadOnStartup=true}
   [junit4]   2> 25616 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 25616 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 25616 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr
   [junit4]   2> 25617 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader
   [junit4]   2> 25618 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 25618 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 25658 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 25675 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.5.0
   [junit4]   2> 25706 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig-spellcheckcomponent.xml
   [junit4]   2> 25715 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 25980 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 25990 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 25991 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 26004 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 26007 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 26009 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 26009 WARN  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 26010 WARN  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 26010 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 26010 WARN  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 26010 WARN  (coreLoadExecutor-137-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 26012 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1'
   [junit4]   2> 26012 INFO  (coreLoadExecutor-137-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1
   [junit4]   2> 26012 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 26012 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001/]
   [junit4]   2> 26012 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
   [junit4]   2> 26013 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001
   [junit4]   2> 26013 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001/index/
   [junit4]   2> 26013 WARN  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001/index' doesn't exist. Creating new index...
   [junit4]   2> 26013 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001/index
   [junit4]   2> 26014 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=479116534, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
   [junit4]   2> 26014 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@5eecf90d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17d2f52b),segFN=segments_1,generation=1}
   [junit4]   2> 26014 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 26015 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001/
   [junit4]   2> 26017 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 26020 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 26021 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 26022 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 26023 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 26026 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/ping,/admin/properties,/admin/logging,/admin/system,spellCheckWithWordbreak,/admin/file,/admin/plugins,/update/csv,/update/json/docs,standard,/admin/threads,/replication,/update/json,spellCheckCompRH,/schema,/update,/get,spellCheckCompRH1,/admin/luke,/admin/segments,/config,/admin/mbeans
   [junit4]   2> 26027 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 26028 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 26028 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 26028 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=37.58984375, floorSegmentMB=2.1455078125, forceMergeDeletesPctAllowed=2.721390968555494, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 26028 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@5eecf90d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17d2f52b),segFN=segments_1,generation=1}
   [junit4]   2> 26028 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1
   [junit4]   2> 26029 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7b8af797[collection1] main
   [junit4]   2> 26029 WARN  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 26029 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 26029 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 26029 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 26030 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 26041 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default
   [junit4]   2> 26050 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default_teststop
   [junit4]   2> 26064 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=teststop}
   [junit4]   2> 26064 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct_lowerfilt,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=lowerfilt}
   [junit4]   2> 26077 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold
   [junit4]   2> 26078 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=threshold_direct,classname=solr.DirectSolrSpellChecker,field=lowerfilt,spellcheckIndexDir=spellcheckerThreshold,buildOnCommit=true,thresholdTokenFrequency=0.29}
   [junit4]   2> 26078 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold_direct
   [junit4]   2> 26096 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: multipleFields
   [junit4]   2> 26119 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: freq
   [junit4]   2> 26127 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: fqcn
   [junit4]   2> 26127 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 26127 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 26127 INFO  (coreLoadExecutor-137-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 26128 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
   [junit4]   2> 26129 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 26129 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
   [junit4]   2> 26129 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 26129 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
   [junit4]   2> 26129 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
   [junit4]   2> 26129 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 26130 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 26130 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 26131 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 26132 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 26132 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 26133 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 26133 INFO  (searcherExecutor-138-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7b8af797[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 26134 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 3
   [junit4]   2> 26140 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 1
   [junit4]   2> 26148 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 7
   [junit4]   2> 26152 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 2
   [junit4]   2> 26154 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 26161 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 6
   [junit4]   2> 26164 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 1
   [junit4]   2> 26166 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 26174 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 6
   [junit4]   2> 26176 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 1
   [junit4]   2> 26178 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[10]} 0 0
   [junit4]   2> 26186 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[11]} 0 6
   [junit4]   2> 26189 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[12]} 0 1
   [junit4]   2> 26191 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[13]} 0 0
   [junit4]   2> 26198 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[14]} 0 5
   [junit4]   2> 26201 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[15]} 0 1
   [junit4]   2> 26202 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[16]} 0 0
   [junit4]   2> 26203 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 26232 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@5eecf90d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17d2f52b),segFN=segments_1,generation=1}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@5eecf90d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17d2f52b),segFN=segments_2,generation=2}
   [junit4]   2> 26232 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.SolrDeletionPolicy newest commit generation = 2
   [junit4]   2> 26234 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@77e0693f[collection1] main
   [junit4]   2> 26235 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 26235 INFO  (searcherExecutor-138-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 26296 INFO  (searcherExecutor-138-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 26357 INFO  (searcherExecutor-138-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 26369 INFO  (searcherExecutor-138-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 26369 INFO  (searcherExecutor-138-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 26433 INFO  (searcherExecutor-138-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 26507 INFO  (searcherExecutor-138-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 26547 INFO  (searcherExecutor-138-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@77e0693f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.5.0):C3) Uninverting(_1(5.5.0):C3) Uninverting(_2(5.5.0):C3) Uninverting(_3(5.5.0):C3) Uninverting(_4(5.5.0):C3) Uninverting(_5(5.5.0):C2)))}
   [junit4]   2> 26548 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 344
   [junit4]   2> 26556 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithOverride
   [junit4]   2> 26720 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck.collate=true&qt=spellCheckCompRH&spellcheck.count=10&spellcheck.maxCollationTries=10&qf=teststop&defType=edismax&wt=xml&mm=1&spellcheck.maxCollations=10&spellcheck.dictionary=direct&q=partisian+politcal+mashine&spellcheck=true} hits=0 status=0 QTime=164
   [junit4]   2> 26771 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck.collate=true&qt=spellCheckCompRH&spellcheck.count=10&spellcheck.maxCollationTries=10&qf=teststop&defType=edismax&wt=xml&mm=1&spellcheck.maxCollations=10&spellcheck.dictionary=direct&spellcheck.collateParam.mm=100%25&q=partisian+politcal+mashine&spellcheck=true} hits=0 status=0 QTime=48
   [junit4]   2> 26772 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithOverride
   [junit4]   2> 26779 INFO  (TEST-SpellCheckCollatorTest.testCollationWithRangeQuery-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollationWithRangeQuery
   [junit4]   1> id:[1 TO 10] AND lowerfilt:love
   [junit4]   2> 26836 INFO  (TEST-SpellCheckCollatorTest.testCollationWithRangeQuery-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollationWithRangeQuery
   [junit4]   2> 26842 INFO  (TEST-SpellCheckCollatorTest.testCollateWithGrouping-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithGrouping
   [junit4]   2> 26921 INFO  (TEST-SpellCheckCollatorTest.testCollateWithGrouping-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithGrouping
   [junit4]   2> 26927 INFO  (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithMultipleRequestHandlers
   [junit4]   2> 26981 INFO  (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithMultipleRequestHandlers
   [junit4]   2> 26988 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testEstimatedHitCounts
   [junit4]   2> 26997 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia} hits=0 status=0 QTime=8
   [junit4]   2> 27008 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=0} hits=0 status=0 QTime=7
   [junit4]   2> 27018 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=30} hits=0 status=0 QTime=8
   [junit4]   2> 27028 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=100} hits=0 status=0 QTime=7
   [junit4]   2> 27037 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=10000} hits=0 status=0 QTime=7
   [junit4]   2> 27047 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=2} hits=0 status=0 QTime=7
   [junit4]   2> 27057 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=13} hits=0 status=0 QTime=7
   [junit4]   2> 27066 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=17} hits=0 status=0 QTime=6
   [junit4]   2> 27076 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=1} hits=0 status=0 QTime=7
   [junit4]   2> 27085 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=17} hits=0 status=0 QTime=6
   [junit4]   2> 27094 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=10} hits=0 status=0 QTime=6
   [junit4]   2> 27103 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=6} hits=0 status=0 QTime=6
   [junit4]   2> 27113 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=8} hits=0 status=0 QTime=6
   [junit4]   2> 27122 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=9} hits=0 status=0 QTime=6
   [junit4]   2> 27131 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=10} hits=0 status=0 QTime=6
   [junit4]   2> 27140 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=16} hits=0 status=0 QTime=6
   [junit4]   2> 27149 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=10} hits=0 status=0 QTime=6
   [junit4]   2> 27156 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=1} hits=0 status=0 QTime=5
   [junit4]   2> 27165 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=13} hits=0 status=0 QTime=5
   [junit4]   2> 27172 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=15} hits=0 status=0 QTime=5
   [junit4]   2> 27180 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=8} hits=0 status=0 QTime=5
   [junit4]   2> 27187 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=17} hits=0 status=0 QTime=5
   [junit4]   2> 27195 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=5
   [junit4]   2> 27203 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=7} hits=0 status=0 QTime=5
   [junit4]   2> 27210 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=4} hits=0 status=0 QTime=5
   [junit4]   2> 27217 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=2} hits=0 status=0 QTime=4
   [junit4]   2> 27225 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:metnoia&spellcheck.collateMaxCollectDocs=13} hits=0 status=0 QTime=5
   [junit4]   2> 27232 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=5
   [junit4]   2> 27235 ERROR (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 6 <= . and . <= 10]
   [junit4]   2> 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">5</int></lst><result name="response" numFound="0" start="0"></result><lst name="spellcheck"><lst name="suggestions"><lst name="everother"><int name="numFound">1</int><int name="startOffset">9</int><int name="endOffset">18</int><arr name="suggestion"><str>everyother</str></arr></lst></lst><lst name="collations"><lst name="collation"><str name="collationQuery">teststop:everyother</str><int name="hits">12</int><lst name="misspellingsAndCorrections"><str name="everother">everyother</str></lst></lst></lst></lst>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2> 	request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
   [junit4]   2> 27236 ERROR (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5:java.lang.RuntimeException: REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 6 <= . and . <= 10]
   [junit4]   2> 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]   2> <response>
   [junit4]   2> <lst name="responseHeader"><int name="status">0</int><int name="QTime">5</int></lst><result name="response" numFound="0" start="0"></result><lst name="spellcheck"><lst name="suggestions"><lst name="everother"><int name="numFound">1</int><int name="startOffset">9</int><int name="endOffset">18</int><arr name="suggestion"><str>everyother</str></arr></lst></lst><lst name="collations"><lst name="collation"><str name="collationQuery">teststop:everyother</str><int name="hits">12</int><lst name="misspellingsAndCorrections"><str name="everother">everyother</str></lst></lst></lst></lst>
   [junit4]   2> </response>
   [junit4]   2> 
   [junit4]   2> 	request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:742)
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716)
   [junit4]   2> 	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:562)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:606)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:745)
   [junit4]   2> 
   [junit4]   2> 27236 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testEstimatedHitCounts
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SpellCheckCollatorTest -Dtests.method=testEstimatedHitCounts -Dtests.seed=37BF9ADC1981D209 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=da -Dtests.timezone=Africa/Maseru -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
   [junit4] ERROR   0.26s J1 | SpellCheckCollatorTest.testEstimatedHitCounts <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([37BF9ADC1981D209:60424E9BCBEC2D9]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:749)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:716)
   [junit4]    > 	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:562)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:745)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 6 <= . and . <= 10]
   [junit4]    > 	xml response was: <?xml version="1.0" encoding="UTF-8"?>
   [junit4]    > <response>
   [junit4]    > <lst name="responseHeader"><int name="status">0</int><int name="QTime">5</int></lst><result name="response" numFound="0" start="0"></result><lst name="spellcheck"><lst name="suggestions"><lst name="everother"><int name="numFound">1</int><int name="startOffset">9</int><int name="endOffset">18</int><arr name="suggestion"><str>everyother</str></arr></lst></lst><lst name="collations"><lst name="collation"><str name="collationQuery">teststop:everyother</str><int name="hits">12</int><lst name="misspellingsAndCorrections"><str name="everother">everyother</str></lst></lst></lst></lst>
   [junit4]    > </response>
   [junit4]    > 	request was:spellcheck=true&spellcheck.dictionary=direct&spellcheck.count=1&spellcheck.collate=true&spellcheck.maxCollationTries=1&spellcheck.maxCollations=1&spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&q=teststop:everother&spellcheck.collateMaxCollectDocs=5
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:742)
   [junit4]    > 	... 41 more
   [junit4]   2> 27247 INFO  (TEST-SpellCheckCollatorTest.testCollateWithFilter-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithFilter
   [junit4]   2> 27336 INFO  (TEST-SpellCheckCollatorTest.testCollateWithFilter-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithFilter
   [junit4]   2> 27344 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testContextSensitiveCollate
   [junit4]   2> 27356 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=spellCheckCompRH&spellcheck.count=10&spellcheck.maxCollationTries=10&spellcheck.build=true&wt=xml&spellcheck.maxCollations=1&spellcheck=true&spellcheck.maxResultsForSuggest=0&spellcheck.collate=true&spellcheck.collateExtendedResults=true&spellcheck.alternativeTermCount=5&indent=true&spellcheck.dictionary=direct&spellcheck.extendedResults=true&q=teststop:(flew+AND+form+AND+heathrow)} hits=0 status=0 QTime=12
   [junit4]   2> 27371 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=spellCheckCompRH&spellcheck.count=10&spellcheck.maxCollationTries=10&wt=xml&spellcheck.maxCollations=1&spellcheck=true&spellcheck.maxResultsForSuggest=1&spellcheck.collate=true&spellcheck.collateExtendedResults=true&spellcheck.alternativeTermCount=5&indent=true&spellcheck.dictionary=direct&spellcheck.extendedResults=true&q=teststop:(june+AND+customs)} hits=1 status=0 QTime=7
   [junit4]   2> 27386 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck.collate=true&spellcheck.count=10&qt=spellCheckCompRH&wt=xml&spellcheck.alternativeTermCount=0&mm=2&indent=true&spellcheck.dictionary=direct&spellcheck=true&q=teststop:(june+customs)} hits=2 status=0 QTime=3
   [junit4]   2> 27468 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=spellCheckCompRH&spellcheck.count=10&spellcheck.maxCollationTries=10&spellcheck.build=true&wt=xml&spellcheck.maxCollations=1&spellcheck=true&spellcheck.maxResultsForSuggest=0&spellcheck.collate=true&spellcheck.collateExtendedResults=true&spellcheck.alternativeTermCount=5&indent=true&spellcheck.dictionary=default_teststop&spellcheck.extendedResults=true&q=teststop:(flew+AND+form+AND+heathrow)} hits=0 status=0 QTime=80
   [junit4]   2> 27487 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={qt=spellCheckCompRH&spellcheck.count=10&spellcheck.maxCollationTries=10&wt=xml&spellcheck.maxCollations=1&spellcheck=true&spellcheck.maxResultsForSuggest=1&spellcheck.collate=true&spellcheck.collateExtendedResults=true&spellcheck.alternativeTermCount=5&indent=true&spellcheck.dictionary=default_teststop&spellcheck.extendedResults=true&q=teststop:(june+AND+customs)} hits=1 status=0 QTime=11
   [junit4]   2> 27496 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={spellcheck.collate=true&spellcheck.count=10&qt=spellCheckCompRH&wt=xml&spellcheck.alternativeTermCount=0&mm=2&indent=true&spellcheck.dictionary=default_teststop&spellcheck=true&q=teststop:(june+customs)} hits=2 status=0 QTime=3
   [junit4]   2> 27497 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testContextSensitiveCollate
   [junit4]   2> 27503 INFO  (TEST-SpellCheckCollatorTest.testZeroTries-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testZeroTries
   [junit4]   2> 27547 INFO  (TEST-SpellCheckCollatorTest.testZeroTries-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testZeroTries
   [junit4]   2> 27553 INFO  (TEST-SpellCheckCollatorTest.testExtendedCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExtendedCollate
   [junit4]   2> 27653 INFO  (TEST-SpellCheckCollatorTest.testExtendedCollate-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExtendedCollate
   [junit4]   2> 27659 INFO  (TEST-SpellCheckCollatorTest.testCollationWithHypens-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollationWithHypens
   [junit4]   2> 27763 INFO  (TEST-SpellCheckCollatorTest.testCollationWithHypens-seed#[37BF9ADC1981D209]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollationWithHypens
   [junit4]   2> 27763 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 27763 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1521495105
   [junit4]   2> 27764 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@343827c
   [junit4]   2> 27764 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.u.DirectUpdateHandler2 closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=17,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
   [junit4]   2> 27764 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 27764 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 27765 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 27766 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 27767 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 27767 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001/index [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001/index;done=false>>]
   [junit4]   2> 27767 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001/index
   [junit4]   2> 27767 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001 [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001;done=false>>]
   [junit4]   2> 27767 INFO  (SUITE-SpellCheckCollatorTest-seed#[37BF9ADC1981D209]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001/init-core-data-001
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_37BF9ADC1981D209-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene54), sim=DefaultSimilarity, locale=da, timezone=Africa/Maseru
   [junit4]   2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=1,free=217771960,total=340262912
   [junit4]   2> NOTE: All tests run in this JVM: [MultiTermTest, TestObjectReleaseTracker, TestAddFieldRealTimeGet, TestSimpleTrackingShardHandler, BasicFunctionalityTest, SpellCheckCollatorTest]
   [junit4] Completed [8/560 (1!)] on J1 in 2.63s, 10 tests, 1 error <<< FAILURES!

[...truncated 1746 lines...]
BUILD FAILED
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:794: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:738: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:59: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:233: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:526: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1456: The following error occurred while executing this line:
/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1010: There were test failures: 560 suites (7 ignored), 2225 tests, 1 error, 53 ignored (38 assumptions) [seed: 37BF9ADC1981D209]

Total time: 63 minutes 27 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any