You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/09/24 17:12:01 UTC

[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0_60) - Build # 5282 - Failure!

Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/5282/
Java: 32bit/jdk1.8.0_60 -server -XX:+UseSerialGC

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([71041E64357D6027:40BFA051904270F7]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:765)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:732)
	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:530)
	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:497)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:864)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:900)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914)
	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:873)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820)
	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:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	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">2</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">14</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%3Aeverother&spellcheck.collateMaxCollectDocs=5
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:758)
	... 41 more




Build Log:
[...truncated 10244 lines...]
   [junit4] Suite: org.apache.solr.spelling.SpellCheckCollatorTest
   [junit4]   2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001
   [junit4]   2> 1675464 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
   [junit4]   2> 1675466 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 1675466 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1675468 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1675469 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1675535 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1675540 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1675551 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig-spellcheckcomponent.xml
   [junit4]   2> 1675552 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1675559 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 1675663 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1675671 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1675671 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1675675 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1675678 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1675680 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1675681 WARN  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1675681 WARN  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1675681 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1675682 WARN  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1675682 WARN  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1675682 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 1675682 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrResourceLoader using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr
   [junit4]   2> 1675682 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\'
   [junit4]   2> 1675703 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.CoreContainer New CoreContainer 20341772
   [junit4]   2> 1675703 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\]
   [junit4]   2> 1675703 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.CoreContainer loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\lib
   [junit4]   2> 1675703 WARN  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\lib).
   [junit4]   2> 1675714 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-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> 1675718 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 1675718 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 1675718 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 1675718 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
   [junit4]   2> 1675718 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.CoreContainer No authentication plugin used.
   [junit4]   2> 1675719 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig-spellcheckcomponent.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=collection1, collection=collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001, shard=shard1}
   [junit4]   2> 1675720 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\'
   [junit4]   2> 1675720 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader
   [junit4]   2> 1675720 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.c.SolrResourceLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/core/src/test-files/solr/collection1/lib/README' to classloader
   [junit4]   2> 1675742 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.c.SolrConfig current version of requestparams : -1
   [junit4]   2> 1675746 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
   [junit4]   2> 1675752 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.c.Config Loaded SolrConfig: solrconfig-spellcheckcomponent.xml
   [junit4]   2> 1675753 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.IndexSchema Reading Solr Schema from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml
   [junit4]   2> 1675759 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 1675844 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 1675848 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.IndexSchema default search field in schema is text
   [junit4]   2> 1675849 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.IndexSchema unique key field: id
   [junit4]   2> 1675853 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1675855 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
   [junit4]   2> 1675856 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1675856 WARN  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1675856 WARN  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1675856 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 1675857 WARN  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
   [junit4]   2> 1675857 WARN  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
   [junit4]   2> 1675860 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf'
   [junit4]   2> 1675860 INFO  (coreLoadExecutor-5478-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\
   [junit4]   2> 1675860 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 1675860 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\], dataDir=[null]
   [junit4]   2> 1675860 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore JMX monitoring not detected for core: collection1
   [junit4]   2> 1675861 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\
   [junit4]   2> 1675861 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\index/
   [junit4]   2> 1675861 WARN  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\index' doesn't exist. Creating new index...
   [junit4]   2> 1675861 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\index
   [junit4]   2> 1675861 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=49, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 1675862 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@ef7f38 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a85657),segFN=segments_1,generation=1}
   [junit4]   2> 1675862 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1675862 INFO  (OldIndexDirectoryCleanupThreadForCore-collection1) [    x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\
   [junit4]   2> 1675864 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 1675868 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1675869 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1675870 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1675870 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
   [junit4]   2> 1675871 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,spellCheckCompRH,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,spellCheckCompRH1,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,spellCheckWithWordbreak,/update/json,/admin/threads,/admin/ping,/update,/admin/file
   [junit4]   2> 1675872 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
   [junit4]   2> 1675872 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
   [junit4]   2> 1675872 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
   [junit4]   2> 1675873 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=43, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=86.5615234375, floorSegmentMB=2.1220703125, forceMergeDeletesPctAllowed=11.877637372716157, segmentsPerTier=24.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7879136912141512
   [junit4]   2> 1675873 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@ef7f38 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a85657),segFN=segments_1,generation=1}
   [junit4]   2> 1675873 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.SolrCore newest commit generation = 1
   [junit4]   2> 1675873 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@16efe11[collection1] main
   [junit4]   2> 1675873 WARN  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf; switching to use InMemory storage instead.
   [junit4]   2> 1675874 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
   [junit4]   2> 1675874 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using InMemoryStorage
   [junit4]   2> 1675874 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
   [junit4]   2> 1675874 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 1675905 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default
   [junit4]   2> 1675943 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default_teststop
   [junit4]   2> 1675944 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=teststop}
   [junit4]   2> 1675944 INFO  (coreLoadExecutor-5478-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> 1675980 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold
   [junit4]   2> 1675980 INFO  (coreLoadExecutor-5478-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> 1675980 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold_direct
   [junit4]   2> 1676015 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: multipleFields
   [junit4]   2> 1676084 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: freq
   [junit4]   2> 1676103 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: fqcn
   [junit4]   2> 1676104 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 1676104 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 1676104 INFO  (coreLoadExecutor-5478-thread-1) [    x:collection1] o.a.s.c.CoreContainer registering core: collection1
   [junit4]   2> 1676104 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
   [junit4]   2> 1676105 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 1676105 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
   [junit4]   2> 1676105 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 1676105 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
   [junit4]   2> 1676105 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
   [junit4]   2> 1676105 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 1676106 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 1676106 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 1676106 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 1676107 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 1676107 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[0]} 0 0
   [junit4]   2> 1676107 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 1676107 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 1676108 INFO  (searcherExecutor-5479-thread-1-processing-x:collection1) [    x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@16efe11[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 1676111 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[1]} 0 2
   [junit4]   2> 1676111 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[2]} 0 0
   [junit4]   2> 1676113 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[3]} 0 2
   [junit4]   2> 1676114 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[4]} 0 0
   [junit4]   2> 1676117 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[5]} 0 2
   [junit4]   2> 1676118 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[6]} 0 0
   [junit4]   2> 1676120 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[7]} 0 2
   [junit4]   2> 1676121 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[8]} 0 0
   [junit4]   2> 1676122 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[9]} 0 1
   [junit4]   2> 1676123 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[10]} 0 0
   [junit4]   2> 1676124 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[11]} 0 1
   [junit4]   2> 1676125 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[12]} 0 0
   [junit4]   2> 1676127 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[13]} 0 1
   [junit4]   2> 1676127 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[14]} 0 0
   [junit4]   2> 1676128 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[15]} 0 1
   [junit4]   2> 1676128 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {add=[16]} 0 0
   [junit4]   2> 1676128 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.UpdateHandler start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 1676134 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@ef7f38 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a85657),segFN=segments_1,generation=1}
   [junit4]   2> 	commit{dir=MockDirectoryWrapper(RAMDirectory@ef7f38 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@a85657),segFN=segments_2,generation=2}
   [junit4]   2> 1676135 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.SolrCore newest commit generation = 2
   [junit4]   2> 1676135 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.s.SolrIndexSearcher Opening Searcher@f51217[collection1] main
   [junit4]   2> 1676135 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.UpdateHandler end_commit_flush
   [junit4]   2> 1676135 INFO  (searcherExecutor-5479-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 1676245 INFO  (searcherExecutor-5479-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 1676382 INFO  (searcherExecutor-5479-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 1676416 INFO  (searcherExecutor-5479-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 1676416 INFO  (searcherExecutor-5479-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 1676527 INFO  (searcherExecutor-5479-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 1676641 INFO  (searcherExecutor-5479-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 1676733 INFO  (searcherExecutor-5479-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f51217[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.0.0):c2) Uninverting(_1(6.0.0):c2) Uninverting(_2(6.0.0):c2) Uninverting(_3(6.0.0):c2) Uninverting(_4(6.0.0):c2) Uninverting(_5(6.0.0):c2) Uninverting(_6(6.0.0):c2) Uninverting(_7(6.0.0):c2) Uninverting(_8(6.0.0):c1)))}
   [junit4]   2> 1676733 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.u.p.LogUpdateProcessor [collection1] webapp=null path=null params={} {commit=} 0 645
   [junit4]   2> 1676734 INFO  (TEST-SpellCheckCollatorTest.testCollateWithGrouping-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithGrouping
   [junit4]   2> 1676787 INFO  (TEST-SpellCheckCollatorTest.testCollateWithGrouping-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithGrouping
   [junit4]   2> 1676791 INFO  (TEST-SpellCheckCollatorTest.testCollateWithFilter-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithFilter
   [junit4]   2> 1676847 INFO  (TEST-SpellCheckCollatorTest.testCollateWithFilter-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithFilter
   [junit4]   2> 1676849 INFO  (TEST-SpellCheckCollatorTest.testCollationWithHypens-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollationWithHypens
   [junit4]   2> 1677023 INFO  (TEST-SpellCheckCollatorTest.testCollationWithHypens-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollationWithHypens
   [junit4]   2> 1677027 INFO  (TEST-SpellCheckCollatorTest.testZeroTries-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testZeroTries
   [junit4]   2> 1677097 INFO  (TEST-SpellCheckCollatorTest.testZeroTries-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testZeroTries
   [junit4]   2> 1677102 INFO  (TEST-SpellCheckCollatorTest.testExtendedCollate-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExtendedCollate
   [junit4]   2> 1677224 INFO  (TEST-SpellCheckCollatorTest.testExtendedCollate-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExtendedCollate
   [junit4]   2> 1677229 INFO  (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithMultipleRequestHandlers
   [junit4]   2> 1677281 INFO  (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithMultipleRequestHandlers
   [junit4]   2> 1677284 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithOverride
   [junit4]   2> 1677299 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[71041E64357D6027]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={mm=1&q=partisian+politcal+mashine&defType=edismax&spellcheck.dictionary=direct&qt=spellCheckCompRH&spellcheck=true&qf=teststop&spellcheck.maxCollations=10&spellcheck.count=10&spellcheck.maxCollationTries=10&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=17 
   [junit4]   2> 1677313 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[71041E64357D6027]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={mm=1&spellcheck.dictionary=direct&qt=spellCheckCompRH&spellcheck.maxCollations=10&spellcheck.maxCollationTries=10&q=partisian+politcal+mashine&defType=edismax&spellcheck.collateParam.mm=100%25&spellcheck=true&qf=teststop&spellcheck.count=10&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=16 
   [junit4]   2> 1677314 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithOverride
   [junit4]   2> 1677346 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testEstimatedHitCounts
   [junit4]   2> 1677359 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia} hits=0 status=0 QTime=14 
   [junit4]   2> 1677363 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=0} hits=0 status=0 QTime=4 
   [junit4]   2> 1677371 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=30} hits=0 status=0 QTime=7 
   [junit4]   2> 1677376 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=100} hits=0 status=0 QTime=4 
   [junit4]   2> 1677381 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=10000} hits=0 status=0 QTime=5 
   [junit4]   2> 1677385 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=9} hits=0 status=0 QTime=4 
   [junit4]   2> 1677391 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=4} hits=0 status=0 QTime=4 
   [junit4]   2> 1677396 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=13} hits=0 status=0 QTime=3 
   [junit4]   2> 1677400 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=12} hits=0 status=0 QTime=3 
   [junit4]   2> 1677405 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=5 
   [junit4]   2> 1677409 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=10} hits=0 status=0 QTime=4 
   [junit4]   2> 1677412 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=6} hits=0 status=0 QTime=3 
   [junit4]   2> 1677417 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=10} hits=0 status=0 QTime=4 
   [junit4]   2> 1677422 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=16} hits=0 status=0 QTime=4 
   [junit4]   2> 1677427 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=1} hits=0 status=0 QTime=5 
   [junit4]   2> 1677430 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=17} hits=0 status=0 QTime=2 
   [junit4]   2> 1677432 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=3} hits=0 status=0 QTime=2 
   [junit4]   2> 1677436 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=8} hits=0 status=0 QTime=3 
   [junit4]   2> 1677438 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Ametnoia&spellcheck.collateMaxCollectDocs=7} hits=0 status=0 QTime=2 
   [junit4]   2> 1677441 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Aeverother&spellcheck.collateMaxCollectDocs=5} hits=0 status=0 QTime=2 
   [junit4]   2> 1677441 ERROR (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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">2</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">14</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%3Aeverother&spellcheck.collateMaxCollectDocs=5
   [junit4]   2> 1677442 ERROR (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] 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%3Aeverother&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">2</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">14</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%3Aeverother&spellcheck.collateMaxCollectDocs=5
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:758)
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:732)
   [junit4]   2> 	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:530)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2> 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:497)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1665)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:864)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:900)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:914)
   [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:873)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:775)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:809)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:820)
   [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:39)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [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> 1677443 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testEstimatedHitCounts
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SpellCheckCollatorTest -Dtests.method=testEstimatedHitCounts -Dtests.seed=71041E64357D6027 -Dtests.slow=true -Dtests.locale=ro -Dtests.timezone=Indian/Kerguelen -Dtests.asserts=true -Dtests.file.encoding=Cp1252
   [junit4] ERROR   0.14s J1 | SpellCheckCollatorTest.testEstimatedHitCounts <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([71041E64357D6027:40BFA051904270F7]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:765)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:732)
   [junit4]    > 	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:530)
   [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">2</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">14</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%3Aeverother&spellcheck.collateMaxCollectDocs=5
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:758)
   [junit4]    > 	... 41 more
   [junit4]   2> 1677457 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testContextSensitiveCollate
   [junit4]   2> 1677461 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[71041E64357D6027]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&spellcheck.dictionary=direct&indent=true&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&spellcheck.alternativeTermCount=5&spellcheck.build=true&spellcheck.extendedResults=true&q=teststop:(flew+AND+form+AND+heathrow)&spellcheck.maxResultsForSuggest=0&spellcheck=true&spellcheck.count=10&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=4 
   [junit4]   2> 1677465 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[71041E64357D6027]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&spellcheck.dictionary=direct&indent=true&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&spellcheck.alternativeTermCount=5&spellcheck.extendedResults=true&q=teststop:(june+AND+customs)&spellcheck.maxResultsForSuggest=1&spellcheck=true&spellcheck.count=10&wt=xml&spellcheck.collate=true} hits=1 status=0 QTime=2 
   [junit4]   2> 1677468 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[71041E64357D6027]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={mm=2&q=teststop:(june+customs)&qt=spellCheckCompRH&spellcheck.dictionary=direct&indent=true&spellcheck=true&spellcheck.count=10&spellcheck.alternativeTermCount=0&wt=xml&spellcheck.collate=true} hits=2 status=0 QTime=0 
   [junit4]   2> 1677579 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[71041E64357D6027]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&spellcheck.dictionary=default_teststop&indent=true&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&spellcheck.alternativeTermCount=5&spellcheck.build=true&spellcheck.extendedResults=true&q=teststop:(flew+AND+form+AND+heathrow)&spellcheck.maxResultsForSuggest=0&spellcheck=true&spellcheck.count=10&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=128 
   [junit4]   2> 1677584 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[71041E64357D6027]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={spellcheck.collateExtendedResults=true&qt=spellCheckCompRH&spellcheck.dictionary=default_teststop&indent=true&spellcheck.maxCollations=1&spellcheck.maxCollationTries=10&spellcheck.alternativeTermCount=5&spellcheck.extendedResults=true&q=teststop:(june+AND+customs)&spellcheck.maxResultsForSuggest=1&spellcheck=true&spellcheck.count=10&wt=xml&spellcheck.collate=true} hits=1 status=0 QTime=4 
   [junit4]   2> 1677586 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[71041E64357D6027]) [    ] o.a.s.c.S.Request [collection1] webapp=null path=null params={mm=2&q=teststop:(june+customs)&qt=spellCheckCompRH&spellcheck.dictionary=default_teststop&indent=true&spellcheck=true&spellcheck.count=10&spellcheck.alternativeTermCount=0&wt=xml&spellcheck.collate=true} hits=2 status=0 QTime=0 
   [junit4]   2> 1677586 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[71041E64357D6027]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testContextSensitiveCollate
   [junit4]   2> 1677586 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 1677586 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=20341772
   [junit4]   2> 1677587 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@5305f3
   [junit4]   2> 1677588 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.u.UpdateHandler 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> 1677588 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.u.SolrCoreState Closing SolrCoreState
   [junit4]   2> 1677588 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 1677588 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.u.DefaultSolrCoreState closing IndexWriter with IndexWriterCloser
   [junit4]   2> 1677594 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.c.SolrCore [collection1] Closing main searcher on request.
   [junit4]   2> 1677594 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 1677594 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\index;done=false>>]
   [junit4]   2> 1677594 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\index
   [junit4]   2> 1677594 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\;done=false>>]
   [junit4]   2> 1677594 INFO  (SUITE-SpellCheckCollatorTest-seed#[71041E64357D6027]-worker) [    x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001\init-core-data-001\
   [junit4]   2> NOTE: leaving temporary files on disk at: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J1\temp\solr.spelling.SpellCheckCollatorTest_71041E64357D6027-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene53): {start1=PostingsFormat(name=Memory doPackFST= false), range_facet_l_dv=FST50, lowerfilt1=PostingsFormat(name=Memory doPackFST= false), multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), teststop=PostingsFormat(name=LuceneVarGapFixedInterval), intDefault=FSTOrd50, range_facet_l=FSTOrd50, lowerfilt1and2=PostingsFormat(name=LuceneVarGapFixedInterval), end4=PostingsFormat(name=Memory doPackFST= false), end3=PostingsFormat(name=LuceneVarGapFixedInterval), end2=FST50, end1=FSTOrd50, lowerfilt=PostingsFormat(name=LuceneVarGapFixedInterval), gram1=FSTOrd50, id=FST50, range_facet_i_dv=FSTOrd50, gram2=FST50, gram3=PostingsFormat(name=LuceneVarGapFixedInterval), start3=FST50, gram4=PostingsFormat(name=Memory doPackFST= false), start2=PostingsFormat(name=LuceneVarGapFixedInterval), word=FSTOrd50, timestamp=FSTOrd50, start4=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene50), range_facet_i_dv=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=ro, timezone=Indian/Kerguelen
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_60 (32-bit)/cpus=3,threads=1,free=82740936,total=433672192
   [junit4]   2> NOTE: All tests run in this JVM: [ShardSplitTest, TestChildDocTransformer, TestHighFrequencyDictionaryFactory, TestAtomicUpdateErrorCases, TestSolrQueryParser, TestFuzzyAnalyzedSuggestions, TestReversedWildcardFilterFactory, TermVectorComponentTest, SpellPossibilityIteratorTest, DistributedQueueTest, TestSolr4Spatial, TestDefaultSearchFieldResource, TestBinaryResponseWriter, TestRandomRequestDistribution, ClusterStateTest, HdfsUnloadDistributedZkTest, TestCrossCoreJoin, TestBM25SimilarityFactory, TestLRUStatsCache, DirectUpdateHandlerOptimizeTest, WordBreakSolrSpellCheckerTest, TestComplexPhraseQParserPlugin, TestCustomDocTransformer, MultiTermTest, SolrIndexSplitterTest, CursorPagingTest, LeaderInitiatedRecoveryOnCommitTest, TestSolrDynamicMBean, TestSchemaNameResource, QueryParsingTest, BitVectorTest, ZkStateReaderTest, TestCharFilters, SuggesterWFSTTest, TestCollationField, VersionInfoTest, CurrencyFieldOpenExchangeTest, TestSimpleTrackingShardHandler, TestDistribDocBasedVersion, TestReRankQParserPlugin, TestTrie, TestLMJelinekMercerSimilarityFactory, BasicDistributedZk2Test, NotRequiredUniqueKeyTest, TestQuerySenderListener, TestStandardQParsers, TestRangeQuery, RAMDirectoryFactoryTest, RecoveryAfterSoftCommitTest, TestFastOutputStream, TestExpandComponent, TestCopyFieldCollectionResource, TestFoldingMultitermQuery, LukeRequestHandlerTest, TestHighlightDedupGrouping, RankQueryTest, TestOverriddenPrefixQueryForCustomFieldType, AutoCommitTest, CopyFieldTest, TestSolrQueryParserDefaultOperatorResource, ZkStateWriterTest, TestSolrCoreProperties, TestJoin, TimeZoneUtilsTest, UUIDUpdateProcessorFallbackTest, AnalysisErrorHandlingTest, StatsComponentTest, SaslZkACLProviderTest, RequestLoggingTest, TestHdfsUpdateLog, CdcrRequestHandlerTest, SecurityConfHandlerTest, SolrCoreCheckLockOnStartupTest, TestRecoveryHdfs, TestInfoStreamLogging, SliceStateTest, TestReloadAndDeleteDocs, CollectionsAPIDistributedZkTest, CdcrUpdateLogTest, CdcrVersionReplicationTest, CoreAdminHandlerTest, FullSolrCloudDistribCmdsTest, SolrInfoMBeanTest, TestSolrQueryParserResource, TestBadConfig, DistributedQueryComponentCustomSortTest, SearchHandlerTest, TestRawResponseWriter, TestDefaultStatsCache, CachingDirectoryFactoryTest, TestDownShardTolerantSearch, TestMinMaxOnMultiValuedField, TestMaxScoreQueryParser, BigEndianAscendingWordDeserializerTest, TestSchemaResource, TestOmitPositions, SolrCmdDistributorTest, DateMathParserTest, BigEndianAscendingWordSerializerTest, FieldMutatingUpdateProcessorTest, URLClassifyProcessorTest, TestWriterPerf, TestShortCircuitedRequests, TestStressRecovery, TestSortByMinMaxFunction, TestPKIAuthenticationPlugin, TestTolerantSearch, HighlighterMaxOffsetTest, LeaderElectionIntegrationTest, QueryResultKeyTest, DeleteLastCustomShardedReplicaTest, TestScoreJoinQPNoScore, TestMiniSolrCloudClusterSSL, TestManagedSchemaFieldResource, AddBlockUpdateTest, DistanceUnitsTest, DistributedFacetPivotLongTailTest, TestManagedSynonymFilterFactory, SpatialRPTFieldTypeTest, BinaryUpdateRequestHandlerTest, TestFieldResource, SignatureUpdateProcessorFactoryTest, TestManagedSchemaFieldTypeResource, TestRestManager, CleanupOldIndexTest, HighlighterConfigTest, CollectionTooManyReplicasTest, TestImplicitCoreProperties, UpdateRequestProcessorFactoryTest, ChaosMonkeySafeLeaderTest, UnloadDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, BasicZkTest, RecoveryZkTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, TestZkChroot, TestRandomDVFaceting, ShardRoutingCustomTest, TestDistributedGrouping, TestFaceting, TestRecovery, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestReload, TestStressVersions, DistributedTermsComponentTest, SimpleFacetsTest, SolrCoreTest, SpellCheckComponentTest, TestGroupingSearch, QueryElevationComponentTest, PeerSyncTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFiltering, BasicFunctionalityTest, TestIndexSearcher, ShowFileRequestHandlerTest, SimplePostToolTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SpellCheckCollatorTest]
   [junit4] Completed [291/546] on J1 in 2.15s, 9 tests, 1 error <<< FAILURES!

[...truncated 797 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:775: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:719: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:59: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:514: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1432: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:987: There were test failures: 546 suites (8 ignored), 2145 tests, 1 error, 81 ignored (45 assumptions) [seed: 71041E64357D6027]

Total time: 69 minutes 10 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any