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 2017/01/05 14:20:42 UTC

[JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+147) - Build # 2596 - Unstable!

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/2596/
Java: 32bit/jdk-9-ea+147 -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([DAD2288BFEE585D8:EB6996BE5BDA9508]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:818)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:785)
	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:569)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:538)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
	at java.base/java.lang.Thread.run(Thread.java:844)
Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 3 <= . and . <= 13]
	xml response was: <?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">4</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:everother&spellcheck.collateMaxCollectDocs=5
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:811)
	... 40 more




Build Log:
[...truncated 11177 lines...]
   [junit4] Suite: org.apache.solr.spelling.SpellCheckCollatorTest
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_DAD2288BFEE585D8-001/init-core-data-001
   [junit4]   2> 210901 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
   [junit4]   2> 210901 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 210902 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 210991 WARN  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 210991 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 211002 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 211093 WARN  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 211095 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 211119 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
   [junit4]   2> 211122 WARN  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 211123 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f6a592
   [junit4]   2> 211123 WARN  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 211127 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f6a592
   [junit4]   2> 211127 WARN  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 211127 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f6a592
   [junit4]   2> 211127 WARN  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 211128 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f6a592
   [junit4]   2> 211130 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 211239 WARN  (coreLoadExecutor-725-thread-1) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 211240 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.4.0
   [junit4]   2> 211250 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 211348 WARN  (coreLoadExecutor-725-thread-1) [    ] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 211352 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 211389 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 211390 WARN  (coreLoadExecutor-725-thread-1) [    ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer.
   [junit4]   2> 211390 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f6a592
   [junit4]   2> 211390 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_DAD2288BFEE585D8-001/init-core-data-001/]
   [junit4]   2> 211392 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=14, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=49.5361328125, floorSegmentMB=0.53125, forceMergeDeletesPctAllowed=9.441985912496836, segmentsPerTier=45.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
   [junit4]   2> 211411 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 211411 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 211411 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=11, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 211412 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@8da916[collection1] main]
   [junit4]   2> 211413 WARN  (coreLoadExecutor-725-thread-1) [    ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 211414 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 211456 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default
   [junit4]   2> 211511 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default_teststop
   [junit4]   2> 211519 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=teststop}
   [junit4]   2> 211520 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.s.DirectSolrSpellChecker init: {name=direct_lowerfilt,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=lowerfilt}
   [junit4]   2> 211548 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold
   [junit4]   2> 211548 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.s.DirectSolrSpellChecker init: {name=threshold_direct,classname=solr.DirectSolrSpellChecker,field=lowerfilt,spellcheckIndexDir=spellcheckerThreshold,buildOnCommit=true,thresholdTokenFrequency=0.29}
   [junit4]   2> 211548 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold_direct
   [junit4]   2> 211594 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: multipleFields
   [junit4]   2> 211689 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: freq
   [junit4]   2> 211720 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: fqcn
   [junit4]   2> 211720 INFO  (coreLoadExecutor-725-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 211720 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 211721 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
   [junit4]   2> 211722 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 211722 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 211722 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
   [junit4]   2> 211722 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 211722 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
   [junit4]   2> 211723 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
   [junit4]   2> 211723 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 211724 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 211725 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 211725 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 211725 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 3
   [junit4]   2> 211726 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 211727 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 211727 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8da916[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 211731 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 4
   [junit4]   2> 211733 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 1
   [junit4]   2> 211737 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 3
   [junit4]   2> 211738 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 1
   [junit4]   2> 211741 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 2
   [junit4]   2> 211742 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 211745 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 2
   [junit4]   2> 211747 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 1
   [junit4]   2> 211750 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 2
   [junit4]   2> 211756 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[10]} 0 0
   [junit4]   2> 211760 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[11]} 0 3
   [junit4]   2> 211761 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[12]} 0 0
   [junit4]   2> 211763 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[13]} 0 2
   [junit4]   2> 211764 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[14]} 0 0
   [junit4]   2> 211766 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[15]} 0 1
   [junit4]   2> 211766 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[16]} 0 0
   [junit4]   2> 211767 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 211767 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4d6d0d
   [junit4]   2> 211778 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@13b4f85[collection1] main]
   [junit4]   2> 211778 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 211778 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 211916 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 212010 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 212068 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 212068 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 212182 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 212308 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 212431 INFO  (searcherExecutor-726-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13b4f85[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.4.0):C2) Uninverting(_1(6.4.0):C2) Uninverting(_2(6.4.0):C2) Uninverting(_3(6.4.0):C2) Uninverting(_4(6.4.0):C2) Uninverting(_5(6.4.0):C2) Uninverting(_6(6.4.0):C2) Uninverting(_7(6.4.0):C2) Uninverting(_8(6.4.0):C1)))}
   [junit4]   2> 212431 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 664
   [junit4]   2> 212433 INFO  (TEST-SpellCheckCollatorTest.testCollateWithGrouping-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithGrouping
   [junit4]   2> 212572 INFO  (TEST-SpellCheckCollatorTest.testCollateWithGrouping-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithGrouping
   [junit4]   2> 212574 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testEstimatedHitCounts
   [junit4]   2> 212592 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=18
   [junit4]   2> 212600 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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> 212607 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=6
   [junit4]   2> 212613 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=5
   [junit4]   2> 212619 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=4
   [junit4]   2> 212624 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=4
   [junit4]   2> 212629 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=4
   [junit4]   2> 212634 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=14} hits=0 status=0 QTime=4
   [junit4]   2> 212639 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=12} hits=0 status=0 QTime=4
   [junit4]   2> 212643 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=4
   [junit4]   2> 212648 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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> 212652 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=11} hits=0 status=0 QTime=4
   [junit4]   2> 212656 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212661 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=4
   [junit4]   2> 212665 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=14} hits=0 status=0 QTime=3
   [junit4]   2> 212670 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212674 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212678 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212681 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=14} hits=0 status=0 QTime=3
   [junit4]   2> 212685 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3} hits=0 status=0 QTime=3
   [junit4]   2> 212696 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3} hits=0 status=0 QTime=10
   [junit4]   2> 212700 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212704 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=12} hits=0 status=0 QTime=3
   [junit4]   2> 212709 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=4
   [junit4]   2> 212712 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212716 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212720 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=12} hits=0 status=0 QTime=4
   [junit4]   2> 212724 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212727 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212731 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=14} hits=0 status=0 QTime=3
   [junit4]   2> 212735 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212739 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212743 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=4
   [junit4]   2> 212747 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212750 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=12} hits=0 status=0 QTime=3
   [junit4]   2> 212754 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3} hits=0 status=0 QTime=3
   [junit4]   2> 212757 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=12} hits=0 status=0 QTime=3
   [junit4]   2> 212761 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=11} hits=0 status=0 QTime=2
   [junit4]   2> 212764 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212768 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212772 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212775 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212779 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212783 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212786 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212790 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212793 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=12} hits=0 status=0 QTime=3
   [junit4]   2> 212797 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 212802 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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=4
   [junit4]   2> 212802 ERROR (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 3 <= . and . <= 13]
   [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">4</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:everother&spellcheck.collateMaxCollectDocs=5
   [junit4]   2> 212803 ERROR (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] 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 3 <= . and . <= 13]
   [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">4</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:everother&spellcheck.collateMaxCollectDocs=5
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:811)
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:785)
   [junit4]   2> 	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:569)
   [junit4]   2> 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2> 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.base/java.lang.reflect.Method.invoke(Method.java:538)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
   [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:49)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [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:811)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
   [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:45)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
   [junit4]   2> 	at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]   2> 
   [junit4]   2> 212803 INFO  (TEST-SpellCheckCollatorTest.testEstimatedHitCounts-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testEstimatedHitCounts
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SpellCheckCollatorTest -Dtests.method=testEstimatedHitCounts -Dtests.seed=DAD2288BFEE585D8 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=br-FR -Dtests.timezone=Europe/Prague -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.23s J1 | SpellCheckCollatorTest.testEstimatedHitCounts <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([DAD2288BFEE585D8:EB6996BE5BDA9508]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:818)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:785)
   [junit4]    > 	at org.apache.solr.spelling.SpellCheckCollatorTest.testEstimatedHitCounts(SpellCheckCollatorTest.java:569)
   [junit4]    > 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    > 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    > 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    > 	at java.base/java.lang.reflect.Method.invoke(Method.java:538)
   [junit4]    > 	at java.base/java.lang.Thread.run(Thread.java:844)
   [junit4]    > Caused by: java.lang.RuntimeException: REQUEST FAILED: xpath=//lst[@name='spellcheck']/lst[@name='collations']/lst[@name='collation']/int[@name='hits' and 3 <= . and . <= 13]
   [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">4</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:everother&spellcheck.collateMaxCollectDocs=5
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:811)
   [junit4]    > 	... 40 more
   [junit4]   2> 212806 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithOverride
   [junit4]   2> 212837 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[DAD2288BFEE585D8]) [    ] 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=30
   [junit4]   2> 212867 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[DAD2288BFEE585D8]) [    ] 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=29
   [junit4]   2> 212868 INFO  (TEST-SpellCheckCollatorTest.testCollateWithOverride-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithOverride
   [junit4]   2> 212870 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testContextSensitiveCollate
   [junit4]   2> 212882 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[DAD2288BFEE585D8]) [    ] 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=11
   [junit4]   2> 212891 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[DAD2288BFEE585D8]) [    ] 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=8
   [junit4]   2> 212894 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[DAD2288BFEE585D8]) [    ] 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=1
   [junit4]   2> 213032 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[DAD2288BFEE585D8]) [    ] 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=137
   [junit4]   2> 213037 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[DAD2288BFEE585D8]) [    ] 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=3
   [junit4]   2> 213040 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[DAD2288BFEE585D8]) [    ] 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=1
   [junit4]   2> 213041 INFO  (TEST-SpellCheckCollatorTest.testContextSensitiveCollate-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testContextSensitiveCollate
   [junit4]   2> 213043 INFO  (TEST-SpellCheckCollatorTest.testExtendedCollate-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExtendedCollate
   [junit4]   2> 213280 INFO  (TEST-SpellCheckCollatorTest.testExtendedCollate-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExtendedCollate
   [junit4]   2> 213283 INFO  (TEST-SpellCheckCollatorTest.testCollationWithRangeQuery-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollationWithRangeQuery
   [junit4]   1> id:[1 TO 10] AND lowerfilt:love
   [junit4]   2> 213550 INFO  (TEST-SpellCheckCollatorTest.testCollationWithRangeQuery-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollationWithRangeQuery
   [junit4]   2> 213552 INFO  (TEST-SpellCheckCollatorTest.testCollateWithFilter-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithFilter
   [junit4]   2> 213832 INFO  (TEST-SpellCheckCollatorTest.testCollateWithFilter-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithFilter
   [junit4]   2> 213835 INFO  (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollateWithMultipleRequestHandlers
   [junit4]   2> 214173 INFO  (TEST-SpellCheckCollatorTest.testCollateWithMultipleRequestHandlers-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollateWithMultipleRequestHandlers
   [junit4]   2> 214177 INFO  (TEST-SpellCheckCollatorTest.testWithCursorMark-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testWithCursorMark
   [junit4]   2> 214475 INFO  (TEST-SpellCheckCollatorTest.testWithCursorMark-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testWithCursorMark
   [junit4]   2> 214476 INFO  (TEST-SpellCheckCollatorTest.testCollationWithHypens-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollationWithHypens
   [junit4]   2> 215043 INFO  (TEST-SpellCheckCollatorTest.testCollationWithHypens-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollationWithHypens
   [junit4]   2> 215044 INFO  (TEST-SpellCheckCollatorTest.testZeroTries-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testZeroTries
   [junit4]   2> 215374 INFO  (TEST-SpellCheckCollatorTest.testZeroTries-seed#[DAD2288BFEE585D8]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testZeroTries
   [junit4]   2> 215374 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 215374 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=19004120
   [junit4]   2> 215374 INFO  (coreCloseExecutor-730-thread-1) [    ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@fb5ac0
   [junit4]   2> 215377 INFO  (coreCloseExecutor-730-thread-1) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1
   [junit4]   2> 215379 INFO  (SUITE-SpellCheckCollatorTest-seed#[DAD2288BFEE585D8]-worker) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.spelling.SpellCheckCollatorTest_DAD2288BFEE585D8-001
   [junit4]   2> NOTE: test params are: codec=HighCompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=3, maxDocsPerChunk=100, blockSize=10), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=3, blockSize=10)), sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=br-FR, timezone=Europe/Prague
   [junit4]   2> NOTE: Linux 4.4.0-53-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=79887184,total=186068992
   [junit4]   2> NOTE: All tests run in this JVM: [DirectSolrConnectionTest, TestJettySolrRunner, CollectionReloadTest, AnalysisErrorHandlingTest, WrapperMergePolicyFactoryTest, BasicFunctionalityTest, SolrGraphiteReporterTest, JsonLoaderTest, TestQueryUtils, CachingDirectoryFactoryTest, UpdateRequestProcessorFactoryTest, DocValuesTest, TestFastOutputStream, BlockCacheTest, TestConfigReload, TestGraphTermsQParserPlugin, SpellingQueryConverterTest, TestCloudPivotFacet, TestCloudManagedSchema, StressHdfsTest, TestLeaderElectionZkExpiry, TestBM25SimilarityFactory, TestStandardQParsers, TestQueryTypes, PreAnalyzedFieldManagedSchemaCloudTest, TestDefaultSearchFieldResource, TestConfigSetProperties, FileUtilsTest, ConfigSetsAPITest, TestInfoStreamLogging, TestAuthorizationFramework, TestSolrIndexConfig, TestSha256AuthenticationProvider, SmileWriterTest, TestFieldCollectionResource, LeaderElectionContextKeyTest, EchoParamsTest, ClassificationUpdateProcessorIntegrationTest, ShardRoutingCustomTest, SpellCheckCollatorTest]
   [junit4] Completed [73/677 (1!)] on J1 in 4.49s, 11 tests, 1 error <<< FAILURES!

[...truncated 53468 lines...]