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 2016/10/26 15:46:26 UTC

[JENKINS-EA] Lucene-Solr-master-Linux (32bit/jdk-9-ea+140) - Build # 18146 - Still Unstable!

Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18146/
Java: 32bit/jdk-9-ea+140 -client -XX:+UseSerialGC

1 tests failed.
FAILED:  org.apache.solr.handler.component.SpellCheckComponentTest.test

Error Message:
List size mismatch @ spellcheck/suggestions

Stack Trace:
java.lang.RuntimeException: List size mismatch @ spellcheck/suggestions
	at __randomizedtesting.SeedInfo.seed([7D860AE605E75141:F5D2353CAB1B3CB9]:0)
	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:901)
	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:848)
	at org.apache.solr.handler.component.SpellCheckComponentTest.test(SpellCheckComponentTest.java:147)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
	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 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.lang.Thread.run(java.base@9-ea/Thread.java:843)




Build Log:
[...truncated 11498 lines...]
   [junit4] Suite: org.apache.solr.handler.component.SpellCheckComponentTest
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.SpellCheckComponentTest_7D860AE605E75141-001/init-core-data-001
   [junit4]   2> 922908 INFO  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) 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> 922911 INFO  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 922912 INFO  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 923017 WARN  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 923018 INFO  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 923029 INFO  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=test
   [junit4]   2> 923094 WARN  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
   [junit4]   2> 923096 INFO  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 923127 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 923218 WARN  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 923219 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 923229 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=test
   [junit4]   2> 923293 WARN  (coreLoadExecutor-3562-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> 923294 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 923317 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 923317 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.SpellCheckComponentTest_7D860AE605E75141-001/init-core-data-001/]
   [junit4]   2> 923318 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 923324 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 923324 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 923324 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=37, maxMergedSegmentMB=32.72265625, floorSegmentMB=1.634765625, forceMergeDeletesPctAllowed=8.700763730493732, segmentsPerTier=48.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8623009428864581
   [junit4]   2> 923324 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@19d1cfe[collection1] main]
   [junit4]   2> 923324 WARN  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 923324 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 923383 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default
   [junit4]   2> 923435 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default_teststop
   [junit4]   2> 923435 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=teststop}
   [junit4]   2> 923435 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.s.DirectSolrSpellChecker init: {name=direct_lowerfilt,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=lowerfilt}
   [junit4]   2> 923501 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold
   [junit4]   2> 923501 INFO  (coreLoadExecutor-3562-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> 923501 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold_direct
   [junit4]   2> 923560 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: multipleFields
   [junit4]   2> 923739 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: freq
   [junit4]   2> 923782 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: fqcn
   [junit4]   2> 923782 INFO  (coreLoadExecutor-3562-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 923782 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 923783 INFO  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 923784 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
   [junit4]   2> 923785 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Starting test
   [junit4]   2> 923785 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 923785 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
   [junit4]   2> 923785 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 923785 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
   [junit4]   2> 923785 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 923787 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 923787 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
   [junit4]   2> 923787 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 923788 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 923788 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 923788 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 923788 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 923788 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 923789 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 923789 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 923789 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 923789 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 923789 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 923789 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 923790 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 923791 WARN  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.c.SolrCore [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
   [junit4]   2> 923791 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 923792 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@13e6853[collection1] main]
   [junit4]   2> 923792 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 923793 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 923794 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 923794 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@19d1cfe[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 923794 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
   [junit4]   2> 923795 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
   [junit4]   2> 923797 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
   [junit4]   2> 923797 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
   [junit4]   2> 923797 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
   [junit4]   2> 923797 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
   [junit4]   2> 923798 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
   [junit4]   2> 923798 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
   [junit4]   2> 923800 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
   [junit4]   2> 923801 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
   [junit4]   2> 923802 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
   [junit4]   2> 923803 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
   [junit4]   2> 923805 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
   [junit4]   2> 923805 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@13e6853[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C10)))}
   [junit4]   2> 923805 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 15
   [junit4]   2> 923806 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=documemt&qt=spellCheckCompRH&spellcheck=true&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 923806 ERROR (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 query failed JSON validation. error=List size mismatch @ spellcheck/suggestions
   [junit4]   2>  expected =/spellcheck=={'suggestions':['documemt',{'numFound':1,'startOffset':0,'endOffset':8,'suggestion':['document']}]}
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":0},
   [junit4]   2>   "response":{"numFound":0,"start":0,"docs":[]
   [junit4]   2>   },
   [junit4]   2>   "spellcheck":{
   [junit4]   2>     "suggestions":[]}}
   [junit4]   2> 
   [junit4]   2>  request = q=documemt&qt=spellCheckCompRH&spellcheck=true&wt=xml
   [junit4]   2> 923806 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending test
   [junit4]   2> 923806 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 923807 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 923807 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 923807 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 923807 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@42d5bd[collection1] main]
   [junit4]   2> 923807 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 923807 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 923869 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 923926 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 923986 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 923986 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 924055 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 924127 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 924204 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@42d5bd[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 924204 INFO  (TEST-SpellCheckComponentTest.test-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 397
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SpellCheckComponentTest -Dtests.method=test -Dtests.seed=7D860AE605E75141 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=en-FM -Dtests.timezone=Asia/Jakarta -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.42s J1 | SpellCheckComponentTest.test <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: List size mismatch @ spellcheck/suggestions
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([7D860AE605E75141:F5D2353CAB1B3CB9]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:901)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertJQ(SolrTestCaseJ4.java:848)
   [junit4]    > 	at org.apache.solr.handler.component.SpellCheckComponentTest.test(SpellCheckComponentTest.java:147)
   [junit4]    > 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
   [junit4]    > 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
   [junit4]    > 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
   [junit4]    > 	at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
   [junit4]   2> 924208 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCollate
   [junit4]   2> 924208 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 924208 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 924209 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 924209 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 924209 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 924209 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 924209 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 924210 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 924210 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 924210 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 924210 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 924210 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 924213 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1a07e7b[collection1] main]
   [junit4]   2> 924213 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 924213 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 924351 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 924416 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 924594 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 924595 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 924770 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 924921 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 925083 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1a07e7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(7.0.0):C10)))}
   [junit4]   2> 925083 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 873
   [junit4]   2> 925330 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=documemt&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.build=true&spellcheck.collate=true} hits=0 status=0 QTime=246
   [junit4]   2> 925332 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=documemt+lowerfilt:broen^4&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=1
   [junit4]   2> 925333 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=documemtsss+broens&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=0
   [junit4]   2> 925333 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=pixma&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.collate=true} hits=0 status=0 QTime=0
   [junit4]   2> 925333 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCollate
   [junit4]   2> 925333 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 925333 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 925334 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 925334 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 925334 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1718abf[collection1] main]
   [junit4]   2> 925334 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 925334 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 925400 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 925457 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 925512 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 925512 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 925584 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 925651 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 925711 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1718abf[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 925711 INFO  (TEST-SpellCheckComponentTest.testCollate-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 377
   [junit4]   2> 925713 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testThresholdTokenFrequency
   [junit4]   2> 925713 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 925714 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 925714 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 925714 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 925714 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 925714 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 925714 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 925715 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 925715 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 925715 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 925715 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 925715 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 925718 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f687a[collection1] main]
   [junit4]   2> 925719 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 925719 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 925903 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 925973 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 926163 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 926163 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 926349 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 926595 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 926720 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3f687a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(7.0.0):C10)))}
   [junit4]   2> 926736 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 1021
   [junit4]   2> 926738 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=documenq&qt=spellCheckCompRH&spellcheck.dictionary=threshold&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=1
   [junit4]   2> 926746 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=documenq&qt=spellCheckCompRH&spellcheck.dictionary=threshold_direct&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=7
   [junit4]   2> 926751 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testThresholdTokenFrequency
   [junit4]   2> 926751 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 926751 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 926752 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 926752 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 926752 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@198118e[collection1] main]
   [junit4]   2> 926752 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 926752 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 926815 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 926861 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 926911 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 926911 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 926965 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 927007 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 927056 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@198118e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 927056 INFO  (TEST-SpellCheckComponentTest.testThresholdTokenFrequency-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 304
   [junit4]   2> 927058 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testPerDictionary
   [junit4]   2> 927058 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 927059 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 927059 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 927059 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 927059 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 927059 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 927060 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 927060 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 927060 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 927060 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 927060 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 927060 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 927064 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c98c7b[collection1] main]
   [junit4]   2> 927064 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 927064 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 927233 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 927296 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 927451 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 927451 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 927689 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 927952 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 928246 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c98c7b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(7.0.0):C10)))}
   [junit4]   2> 928246 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 1185
   [junit4]   2> 928247 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=documemt&qt=spellCheckCompRH&spellcheck.dictionary=perDict&json.nl=map&spellcheck..perDict.foo=bar&spellcheck=true&spellcheck..perDict.bar=foo&wt=xml&spellcheck.build=true} hits=0 status=0 QTime=0
   [junit4]   2> 928247 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testPerDictionary
   [junit4]   2> 928248 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 928248 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 928248 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 928248 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 928248 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f15c35[collection1] main]
   [junit4]   2> 928248 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 928248 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 928337 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 928433 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 928482 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 928482 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 928552 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 928613 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 928693 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f15c35[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 928693 INFO  (TEST-SpellCheckComponentTest.testPerDictionary-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 445
   [junit4]   2> 928695 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testReloadOnStart
   [junit4]   2> 928696 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 928696 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 928696 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 928697 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 928697 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 928697 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 928697 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 928698 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 928698 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 928699 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 928699 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 928699 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 928702 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b8cf6c[collection1] main]
   [junit4]   2> 928703 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 928703 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 928868 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 928927 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 929078 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 929079 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 929194 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 929321 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 929436 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b8cf6c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C10)))}
   [junit4]   2> 929436 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 737
   [junit4]   2> 929437 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 929437 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 929437 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 929441 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6c84fb[collection1] main]
   [junit4]   2> 929441 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 929441 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 929588 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 929650 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 929788 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 929788 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 929989 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 930107 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 930259 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6c84fb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(7.0.0):C10/1:delGen=1) Uninverting(_5(7.0.0):C1)))}
   [junit4]   2> 930259 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 821
   [junit4]   2> 930496 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&spellcheck.q=ttle&qt=spellCheckCompRH&spellcheck.dictionary=default&spellcheck=true&wt=xml&spellcheck.build=true} hits=10 status=0 QTime=236
   [junit4]   2> 930497 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
   [junit4]   2> 930504 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testReloadOnStart
   [junit4]   2> 930504 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 930505 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 930505 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 930505 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 930505 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@193a9a6[collection1] main]
   [junit4]   2> 930505 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 930505 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 930602 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 930670 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 930731 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 930731 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 930792 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 930877 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 930954 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@193a9a6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 930954 INFO  (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 449
   [junit4]   2> 930955 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testRelativeIndexDirLocation
   [junit4]   2> 930956 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 930956 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 930956 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 930956 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 930957 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 930957 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 930957 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 930957 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 930957 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 930958 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 930958 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 930958 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 930961 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@17066a9[collection1] main]
   [junit4]   2> 930961 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 930961 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 931113 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 931180 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 931383 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 931383 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 931619 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 931897 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 932191 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@17066a9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_6(7.0.0):C10)))}
   [junit4]   2> 932191 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 1233
   [junit4]   2> 932192 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testRelativeIndexDirLocation
   [junit4]   2> 932192 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 932192 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 932193 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 932193 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 932193 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@46f13a[collection1] main]
   [junit4]   2> 932193 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 932193 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 932284 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 932355 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 932428 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 932428 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 932479 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 932531 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 932614 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@46f13a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 932614 INFO  (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 421
   [junit4]   2> 932616 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testNumericQuery
   [junit4]   2> 932616 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 932617 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 932617 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 932617 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 932617 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 932617 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 932618 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 932618 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 932618 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 932619 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 932619 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 932619 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 932622 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@173c980[collection1] main]
   [junit4]   2> 932622 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 932622 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 932736 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 932790 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 932943 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 932943 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 933069 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 933294 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 933414 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@173c980[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(7.0.0):C10)))}
   [junit4]   2> 933415 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 795
   [junit4]   2> 933416 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=12346&qt=spellCheckCompRH&spellcheck=true&wt=xml} hits=0 status=0 QTime=0
   [junit4]   2> 933416 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testNumericQuery
   [junit4]   2> 933416 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 933416 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 933416 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 933416 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 933416 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@130ab5[collection1] main]
   [junit4]   2> 933417 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 933417 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 933473 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 933525 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 933588 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 933588 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 933644 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 933716 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 933772 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@130ab5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 933772 INFO  (TEST-SpellCheckComponentTest.testNumericQuery-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 355
   [junit4]   2> 933774 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testCorrectSpelling
   [junit4]   2> 933775 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 933775 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 933775 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 933776 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 933776 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 933776 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 933776 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 933776 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 933777 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 933777 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 933777 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 933777 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 933779 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b92a7f[collection1] main]
   [junit4]   2> 933779 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 933780 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 933948 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 934004 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 934162 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 934162 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 934325 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 934507 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 934651 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1b92a7f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(7.0.0):C10)))}
   [junit4]   2> 934651 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 873
   [junit4]   2> 934651 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=lowerfilt:lazy+lowerfilt:brown&qt=spellCheckCompRH&json.nl=map&spellcheck=true&wt=xml&spellcheck.extendedResults=true} hits=1 status=0 QTime=0
   [junit4]   2> 934652 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=lowerfilt:lazy+lowerfilt:brown&qt=spellCheckCompRH&spellcheck.dictionary=direct_lowerfilt&json.nl=map&spellcheck=true&wt=xml&spellcheck.extendedResults=true} hits=1 status=0 QTime=0
   [junit4]   2> 934655 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=lakkle&qt=spellCheckCompRH&spellcheck.dictionary=direct_lowerfilt&json.nl=map&spellcheck=true&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=2
   [junit4]   2> 934655 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testCorrectSpelling
   [junit4]   2> 934655 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 934656 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 934656 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 934656 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 934656 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@fc3ed3[collection1] main]
   [junit4]   2> 934656 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 934656 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 934729 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 934786 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 934850 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 934850 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 934914 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 934961 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 935012 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@fc3ed3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 935012 INFO  (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 356
   [junit4]   2> 935014 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testExtendedResultsCount
   [junit4]   2> 935014 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[0]} 0 0
   [junit4]   2> 935014 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 935014 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 935014 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 935015 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[4]} 0 0
   [junit4]   2> 935015 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[5]} 0 0
   [junit4]   2> 935015 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[6]} 0 0
   [junit4]   2> 935015 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[7]} 0 0
   [junit4]   2> 935015 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[8]} 0 0
   [junit4]   2> 935016 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[9]} 0 0
   [junit4]   2> 935016 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 935016 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 935019 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@392c[collection1] main]
   [junit4]   2> 935019 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 935019 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 935166 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 935230 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 935360 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 935360 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 935502 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 935647 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 935758 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@392c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(7.0.0):C10)))}
   [junit4]   2> 935759 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 742
   [junit4]   2> 935923 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=bluo&qt=spellCheckCompRH&spellcheck=true&spellcheck.count=5&wt=xml&spellcheck.build=true&spellcheck.extendedResults=false} hits=0 status=0 QTime=164
   [junit4]   2> 935924 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=bluo&qt=spellCheckCompRH&spellcheck=true&spellcheck.count=3&wt=xml&spellcheck.extendedResults=true} hits=0 status=0 QTime=0
   [junit4]   2> 935924 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testExtendedResultsCount
   [junit4]   2> 935924 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 935924 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 935924 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 935924 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 935925 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@d02569[collection1] main]
   [junit4]   2> 935925 INFO  (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 935925 INFO  (searcherExecutor-3563-thread-1) [   

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

    "range_facet_i_dv":[6],
   [junit4]   2>         "lowerfilt":["this boue"],
   [junit4]   2>         "lowerfilt1and2":["this boue"],
   [junit4]   2>         "multiDefault":["muLti-Default"],
   [junit4]   2>         "intDefault":42,
   [junit4]   2>         "timestamp":"2016-10-26T15:19:29.021Z"},
   [junit4]   2>       {
   [junit4]   2>         "id":7,
   [junit4]   2>         "range_facet_l":[7],
   [junit4]   2>         "range_facet_l_dv":[7],
   [junit4]   2>         "range_facet_i_dv":[7],
   [junit4]   2>         "lowerfilt":["this glue"],
   [junit4]   2>         "lowerfilt1and2":["this glue"],
   [junit4]   2>         "multiDefault":["muLti-Default"],
   [junit4]   2>         "intDefault":42,
   [junit4]   2>         "timestamp":"2016-10-26T15:19:29.021Z"},
   [junit4]   2>       {
   [junit4]   2>         "id":8,
   [junit4]   2>         "range_facet_l":[8],
   [junit4]   2>         "range_facet_l_dv":[8],
   [junit4]   2>         "range_facet_i_dv":[8],
   [junit4]   2>         "lowerfilt":["this blee"],
   [junit4]   2>         "lowerfilt1and2":["this blee"],
   [junit4]   2>         "multiDefault":["muLti-Default"],
   [junit4]   2>         "intDefault":42,
   [junit4]   2>         "timestamp":"2016-10-26T15:19:29.021Z"},
   [junit4]   2>       {
   [junit4]   2>         "id":0,
   [junit4]   2>         "range_facet_l":[0],
   [junit4]   2>         "range_facet_l_dv":[0],
   [junit4]   2>         "range_facet_i_dv":[0],
   [junit4]   2>         "lowerfilt":["This is a title"],
   [junit4]   2>         "lowerfilt1and2":["This is a title"],
   [junit4]   2>         "multiDefault":["muLti-Default"],
   [junit4]   2>         "intDefault":42,
   [junit4]   2>         "timestamp":"2016-10-26T15:19:29.019Z"},
   [junit4]   2>       {
   [junit4]   2>         "id":2,
   [junit4]   2>         "range_facet_l":[2],
   [junit4]   2>         "range_facet_l_dv":[2],
   [junit4]   2>         "range_facet_i_dv":[2],
   [junit4]   2>         "lowerfilt":["This is a document"],
   [junit4]   2>         "lowerfilt1and2":["This is a document"],
   [junit4]   2>         "multiDefault":["muLti-Default"],
   [junit4]   2>         "intDefault":42,
   [junit4]   2>         "timestamp":"2016-10-26T15:19:29.020Z"}]
   [junit4]   2>   },
   [junit4]   2>   "spellcheck":{
   [junit4]   2>     "suggestions":[]}}
   [junit4]   2> 
   [junit4]   2>  request = q=lowerfilt:(this+OR+brwn)&spellcheck.maxResultsForSuggest=.64&qt=spellCheckCompRH&spellcheck=true&fq=id:[0+TO+9]&spellcheck.count=5&wt=xml&spellcheck.build=true&spellcheck.maxResultsForSuggest.fq=lowerfilt:th*&spellcheck.extendedResults=false
   [junit4]   2> 940129 INFO  (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[7D860AE605E75141]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testMaximumResultsForSuggest
   [junit4]   2> 940130 INFO  (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
   [junit4]   2> 940130 INFO  (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{deleteByQuery=*:*} 0 0
   [junit4]   2> 940130 INFO  (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 940130 INFO  (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[7D860AE605E75141]) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@54e82d
   [junit4]   2> 940131 INFO  (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[7D860AE605E75141]) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@81df26[collection1] main]
   [junit4]   2> 940131 INFO  (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[7D860AE605E75141]) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 940131 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
   [junit4]   2> 940174 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
   [junit4]   2> 940221 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
   [junit4]   2> 940278 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
   [junit4]   2> 940278 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
   [junit4]   2> 940333 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
   [junit4]   2> 940395 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
   [junit4]   2> 940452 INFO  (searcherExecutor-3563-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@81df26[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 940452 INFO  (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[7D860AE605E75141]) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 321
   [junit4]   2> 940452 INFO  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 940452 INFO  (SUITE-SpellCheckComponentTest-seed#[7D860AE605E75141]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=30273055
   [junit4]   2> 940453 INFO  (coreCloseExecutor-3567-thread-1) [    ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@189abb2
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.SpellCheckComponentTest_7D860AE605E75141-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene70): {start1=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_l_dv=PostingsFormat(name=LuceneVarGapFixedInterval), multiDefault=Lucene50(blocksize=128), intDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_l=Lucene50(blocksize=128), lowerfilt1and2=Lucene50(blocksize=128), end4=FST50, end3=PostingsFormat(name=LuceneVarGapDocFreqInterval), end2=PostingsFormat(name=LuceneVarGapFixedInterval), end1=Lucene50(blocksize=128), lowerfilt=Lucene50(blocksize=128), gram1=Lucene50(blocksize=128), id=PostingsFormat(name=LuceneVarGapFixedInterval), range_facet_i_dv=Lucene50(blocksize=128), gram2=PostingsFormat(name=LuceneVarGapFixedInterval), gram3=PostingsFormat(name=LuceneVarGapDocFreqInterval), start3=FST50, gram4=FST50, start2=Lucene50(blocksize=128), word=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128), start4=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=559, maxMBSortInHeap=6.74977595297089, sim=ClassicSimilarity, locale=en-FM, timezone=Asia/Jakarta
   [junit4]   2> NOTE: Linux 4.4.0-42-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=199232704,total=416342016
   [junit4]   2> NOTE: All tests run in this JVM: [QueryParsingTest, HdfsUnloadDistributedZkTest, WordBreakSolrSpellCheckerTest, RestartWhileUpdatingTest, TestHdfsUpdateLog, SpatialFilterTest, TestHashPartitioner, IndexSchemaTest, TestSurroundQueryParser, DistanceFunctionTest, ChaosMonkeyNothingIsSafeTest, TestTrieFacet, TlogReplayBufferedWhileIndexingTest, CdcrReplicationHandlerTest, TestSimpleQParserPlugin, TestFieldCollectionResource, DistributedFacetPivotWhiteBoxTest, SolrCloudExampleTest, TestTrie, TolerantUpdateProcessorTest, TestSimpleTrackingShardHandler, AnalyticsQueryTest, TestCharFilters, DocValuesTest, TestClassicSimilarityFactory, TermVectorComponentTest, TestSolrConfigHandler, TestDefaultSearchFieldResource, DistributedExpandComponentTest, TestJsonRequest, TestPartialUpdateDeduplication, SortByFunctionTest, DocumentBuilderTest, TestJsonFacetRefinement, TestExclusionRuleCollectionAccess, TestStressRecovery, TestSolrCloudSnapshots, SoftAutoCommitTest, ConvertedLegacyTest, ZkSolrClientTest, GraphQueryTest, TestSolrConfigHandlerConcurrent, HdfsBasicDistributedZk2Test, ShardRoutingCustomTest, ConjunctionSolrSpellCheckerTest, TestSubQueryTransformer, ShardRoutingTest, TestSolrCloudWithKerberosAlt, ForceLeaderTest, OverseerModifyCollectionTest, ConfigSetsAPITest, CustomCollectionTest, TestSolrQueryParserDefaultOperatorResource, TestRandomRequestDistribution, SolrInfoMBeanTest, HdfsDirectoryTest, SuggesterFSTTest, AliasIntegrationTest, TestCryptoKeys, TestGraphTermsQParserPlugin, CoreMergeIndexesAdminHandlerTest, HdfsDirectoryFactoryTest, TestShortCircuitedRequests, HdfsChaosMonkeySafeLeaderTest, TestGraphMLResponseWriter, TestMiniSolrCloudClusterSSL, TestManagedStopFilterFactory, ClusterStateUpdateTest, HdfsSyncSliceTest, AtomicUpdatesTest, DirectSolrConnectionTest, TestRequestForwarding, DocExpirationUpdateProcessorFactoryTest, TestDistribIDF, SOLR749Test, BadComponentTest, TestLRUStatsCache, MoreLikeThisHandlerTest, UpdateRequestProcessorFactoryTest, TestExpandComponent, TestFileDictionaryLookup, LoggingHandlerTest, TestCloudDeleteByQuery, DistributedMLTComponentTest, TestCustomSort, ChaosMonkeySafeLeaderTest, TestOrdValues, TimeZoneUtilsTest, TestStressVersions, OpenCloseCoreStressTest, SpellCheckComponentTest]
   [junit4] Completed [271/644 (1!)] on J1 in 17.57s, 11 tests, 1 error <<< FAILURES!

[...truncated 51069 lines...]


[JENKINS-EA] Lucene-Solr-master-Linux (64bit/jdk-9-ea+140) - Build # 18147 - Still Unstable!

Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/18147/
Java: 64bit/jdk-9-ea+140 -XX:+UseCompressedOops -XX:+UseG1GC

6 tests failed.
FAILED:  org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=DV}

Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val

Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
	at __randomizedtesting.SeedInfo.seed([7AB5CCD1A6FA4C6D:A67565A7CB7BE8A7]:0)
	at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
	at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
	at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
	at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
	at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
	at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
	at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
	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 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.lang.Thread.run(java.base@9-ea/Thread.java:843)


FAILED:  org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=SMART}

Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val

Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
	at __randomizedtesting.SeedInfo.seed([7AB5CCD1A6FA4C6D:A67565A7CB7BE8A7]:0)
	at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
	at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
	at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
	at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
	at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
	at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
	at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
	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 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.lang.Thread.run(java.base@9-ea/Thread.java:843)


FAILED:  org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=STREAM}

Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val

Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
	at __randomizedtesting.SeedInfo.seed([7AB5CCD1A6FA4C6D:A67565A7CB7BE8A7]:0)
	at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
	at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
	at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
	at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
	at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
	at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
	at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
	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 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.lang.Thread.run(java.base@9-ea/Thread.java:843)


FAILED:  org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=ENUM}

Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val

Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
	at __randomizedtesting.SeedInfo.seed([7AB5CCD1A6FA4C6D:A67565A7CB7BE8A7]:0)
	at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
	at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
	at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
	at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
	at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
	at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
	at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
	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 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.lang.Thread.run(java.base@9-ea/Thread.java:843)


FAILED:  org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=DVHASH}

Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val

Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
	at __randomizedtesting.SeedInfo.seed([7AB5CCD1A6FA4C6D:A67565A7CB7BE8A7]:0)
	at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
	at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
	at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
	at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
	at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
	at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
	at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
	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 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.lang.Thread.run(java.base@9-ea/Thread.java:843)


FAILED:  org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib {p0=UIF}

Error Message:
mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val

Stack Trace:
java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
	at __randomizedtesting.SeedInfo.seed([7AB5CCD1A6FA4C6D:A67565A7CB7BE8A7]:0)
	at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
	at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
	at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
	at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
	at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
	at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
	at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(java.base@9-ea/Method.java:535)
	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 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.lang.Thread.run(java.base@9-ea/Thread.java:843)




Build Log:
[...truncated 11465 lines...]
   [junit4] Suite: org.apache.solr.search.facet.TestJsonFacets
   [junit4]   2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/init-core-data-001
   [junit4]   2> 825184 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None")
   [junit4]   2> 825190 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 825191 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 825262 WARN  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 825263 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 825272 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.s.IndexSchema [null] Schema name=example
   [junit4]   2> 825316 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 825350 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
   [junit4]   2> 825416 WARN  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 825417 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 825428 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 825472 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 825500 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1
   [junit4]   2> 825500 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/init-core-data-001/]
   [junit4]   2> 825500 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6db824f1
   [junit4]   2> 825501 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8481246078004825]
   [junit4]   2> 825503 WARN  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 825508 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 825508 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 825509 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 825509 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 825509 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 825509 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@24cf5042[collection1] main]
   [junit4]   2> 825509 WARN  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
   [junit4]   2> 825510 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 825510 INFO  (searcherExecutor-2907-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@24cf5042[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 825511 INFO  (coreLoadExecutor-2906-thread-1) [    ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549277144873435136
   [junit4]   2> 825514 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 825515 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testBigger {p0=DV}
   [junit4]   2> 825569 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 825570 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6942f2c0{/solr,null,AVAILABLE}
   [junit4]   2> 825571 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@5afc34ed{HTTP/1.1,[http/1.1]}{127.0.0.1:33092}
   [junit4]   2> 825571 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.Server Started @827756ms
   [junit4]   2> 825571 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, hostContext=/solr, solrconfig=solrconfig-tlog.xml, hostPort=33092}
   [junit4]   2> 825572 ERROR (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 825572 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 825572 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 825572 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 825572 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-10-26T18:17:02.122479Z
   [junit4]   2> 825572 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s0-001/solr.xml
   [junit4]   2> 825587 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s0-001/.
   [junit4]   2> 825587 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 825595 WARN  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 825596 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 825605 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 825654 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 825680 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s0-001/./collection1
   [junit4]   2> 825680 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s0-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s0-001/./collection1/data/]
   [junit4]   2> 825680 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6db824f1
   [junit4]   2> 825681 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8481246078004825]
   [junit4]   2> 825683 WARN  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 825689 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 825689 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 825690 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 825690 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 825691 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 825691 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5d79b4be[collection1] main]
   [junit4]   2> 825692 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s0-001/collection1/conf
   [junit4]   2> 825692 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 825693 INFO  (searcherExecutor-2918-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5d79b4be[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 825693 INFO  (coreLoadExecutor-2917-thread-1) [    ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549277145064275968
   [junit4]   2> 826088 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.SolrTestCaseHS ===> Started solr server port=33092 home=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s0-001
   [junit4]   2> 826175 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 826175 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c8466ae{/solr,null,AVAILABLE}
   [junit4]   2> 826176 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@4cf44595{HTTP/1.1,[http/1.1]}{127.0.0.1:42257}
   [junit4]   2> 826176 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.Server Started @828360ms
   [junit4]   2> 826176 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, hostContext=/solr, solrconfig=solrconfig-tlog.xml, hostPort=42257}
   [junit4]   2> 826176 ERROR (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 826176 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 826176 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 826176 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 826176 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-10-26T18:17:02.726799Z
   [junit4]   2> 826177 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s1-001/solr.xml
   [junit4]   2> 826191 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s1-001/.
   [junit4]   2> 826192 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 826199 WARN  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 826200 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 826210 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 826257 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 826277 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s1-001/./collection1
   [junit4]   2> 826278 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s1-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s1-001/./collection1/data/]
   [junit4]   2> 826278 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6db824f1
   [junit4]   2> 826279 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8481246078004825]
   [junit4]   2> 826284 WARN  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 826294 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 826294 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 826296 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 826296 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 826297 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 826297 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@64dd4066[collection1] main]
   [junit4]   2> 826298 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s1-001/collection1/conf
   [junit4]   2> 826299 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 826299 INFO  (searcherExecutor-2929-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@64dd4066[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 826300 INFO  (coreLoadExecutor-2928-thread-1) [    ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549277145700761600
   [junit4]   2> 826696 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.SolrTestCaseHS ===> Started solr server port=42257 home=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s1-001
   [junit4]   2> 826779 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.Server jetty-9.3.8.v20160314
   [junit4]   2> 826780 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@704efae8{/solr,null,AVAILABLE}
   [junit4]   2> 826780 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.ServerConnector Started ServerConnector@a643fe7{HTTP/1.1,[http/1.1]}{127.0.0.1:39087}
   [junit4]   2> 826780 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.e.j.s.Server Started @828965ms
   [junit4]   2> 826780 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {schema=schema_latest.xml, hostContext=/solr, solrconfig=solrconfig-tlog.xml, hostPort=39087}
   [junit4]   2> 826781 ERROR (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 826781 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 7.0.0
   [junit4]   2> 826781 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in standalone mode on port null
   [junit4]   2> 826781 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 826781 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2016-10-26T18:17:03.331564Z
   [junit4]   2> 826782 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s2-001/solr.xml
   [junit4]   2> 826807 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s2-001/.
   [junit4]   2> 826807 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
   [junit4]   2> 826818 WARN  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead.
   [junit4]   2> 826820 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
   [junit4]   2> 826834 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.s.IndexSchema [collection1] Schema name=example
   [junit4]   2> 826915 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
   [junit4]   2> 826933 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s2-001/./collection1
   [junit4]   2> 826933 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s2-001/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s2-001/./collection1/data/]
   [junit4]   2> 826934 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@6db824f1
   [junit4]   2> 826934 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=42, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8481246078004825]
   [junit4]   2> 826937 WARN  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
   [junit4]   2> 826943 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 826943 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 826944 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 826944 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 826945 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=37, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
   [junit4]   2> 826945 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4756910[collection1] main]
   [junit4]   2> 826945 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.r.ManagedResourceStorage File-based storage initialized to use dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s2-001/collection1/conf
   [junit4]   2> 826946 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.h.ReplicationHandler Commits will be reserved for  10000
   [junit4]   2> 826946 INFO  (searcherExecutor-2940-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4756910[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 826947 INFO  (coreLoadExecutor-2939-thread-1) [    ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1549277146379190272
   [junit4]   2> 827311 INFO  (TEST-TestJsonFacets.testBigger-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.SolrTestCaseHS ===> Started solr server port=39087 home=/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001/s2-001
   [junit4]   2> 827321 INFO  (qtp1695755482-7135) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1549277146768211968)} 0 2
   [junit4]   2> 827323 INFO  (qtp1765772251-7151) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1549277146771357696)} 0 1
   [junit4]   2> 827325 INFO  (qtp1930404505-7167) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1549277146774503424)} 0 1
   [junit4]   2> 827326 INFO  (qtp1765772251-7152) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[0 (1549277146776600576)]} 0 0
   [junit4]   2> 827327 INFO  (qtp1930404505-7168) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1549277146776600576)]} 0 0
   [junit4]   2> 827327 INFO  (qtp1695755482-7136) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1549277146777649152)]} 0 0
   [junit4]   2> 827328 INFO  (qtp1765772251-7153) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[3 (1549277146778697728)]} 0 0
   [junit4]   2> 827328 INFO  (qtp1930404505-7169) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[4 (1549277146778697728)]} 0 0
   [junit4]   2> 827329 INFO  (qtp1695755482-7137) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[5 (1549277146779746304)]} 0 0
   [junit4]   2> 827329 INFO  (qtp1765772251-7146) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[6 (1549277146779746304)]} 0 0
   [junit4]   2> 827329 INFO  (qtp1930404505-7162) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[7 (1549277146779746304)]} 0 0
   [junit4]   2> 827330 INFO  (qtp1695755482-7130) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[8 (1549277146780794880)]} 0 0
   [junit4]   2> 827330 INFO  (qtp1695755482-7135) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827330 INFO  (qtp1695755482-7135) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23a46954
   [junit4]   2> 827332 INFO  (qtp1695755482-7135) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3ff95814[collection1] main]
   [junit4]   2> 827332 INFO  (qtp1695755482-7135) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827333 INFO  (searcherExecutor-2918-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3ff95814[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)))}
   [junit4]   2> 827333 INFO  (qtp1695755482-7135) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827333 INFO  (qtp1765772251-7151) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827333 INFO  (qtp1765772251-7151) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@650930c7
   [junit4]   2> 827334 INFO  (qtp1765772251-7151) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1509116d[collection1] main]
   [junit4]   2> 827334 INFO  (qtp1765772251-7151) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827335 INFO  (searcherExecutor-2929-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1509116d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)))}
   [junit4]   2> 827335 INFO  (qtp1765772251-7151) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
   [junit4]   2> 827336 INFO  (qtp1930404505-7167) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827336 INFO  (qtp1930404505-7167) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@718d27e
   [junit4]   2> 827337 INFO  (qtp1930404505-7167) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3978d318[collection1] main]
   [junit4]   2> 827337 INFO  (qtp1930404505-7167) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827338 INFO  (searcherExecutor-2940-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3978d318[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3)))}
   [junit4]   2> 827338 INFO  (qtp1930404505-7167) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
   [junit4]   2> 827340 INFO  (qtp1765772251-7152) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[9 (1549277146789183488)]} 0 1
   [junit4]   2> 827340 INFO  (qtp1765772251-7153) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[10 (1549277146791280640)]} 0 0
   [junit4]   2> 827340 INFO  (qtp1765772251-7146) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[11 (1549277146791280641)]} 0 0
   [junit4]   2> 827342 INFO  (qtp1930404505-7168) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[12 (1549277146792329216)]} 0 0
   [junit4]   2> 827343 INFO  (qtp1695755482-7136) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[13 (1549277146793377792)]} 0 0
   [junit4]   2> 827343 INFO  (qtp1765772251-7151) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[14 (1549277146794426368)]} 0 0
   [junit4]   2> 827343 INFO  (qtp1930404505-7169) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[15 (1549277146794426368)]} 0 0
   [junit4]   2> 827344 INFO  (qtp1695755482-7137) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[16 (1549277146795474944)]} 0 0
   [junit4]   2> 827344 INFO  (qtp1765772251-7152) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[17 (1549277146795474944)]} 0 0
   [junit4]   2> 827344 INFO  (qtp1695755482-7130) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827344 INFO  (qtp1695755482-7130) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23a46954
   [junit4]   2> 827346 INFO  (qtp1695755482-7130) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7a2de0a5[collection1] main]
   [junit4]   2> 827346 INFO  (qtp1695755482-7130) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827346 INFO  (searcherExecutor-2918-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7a2de0a5[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2)))}
   [junit4]   2> 827346 INFO  (qtp1695755482-7130) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
   [junit4]   2> 827347 INFO  (qtp1765772251-7153) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827347 INFO  (qtp1765772251-7153) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@650930c7
   [junit4]   2> 827349 INFO  (qtp1765772251-7153) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2e3ed25c[collection1] main]
   [junit4]   2> 827349 INFO  (qtp1765772251-7153) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827349 INFO  (searcherExecutor-2929-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2e3ed25c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C5)))}
   [junit4]   2> 827349 INFO  (qtp1765772251-7153) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827349 INFO  (qtp1930404505-7162) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827350 INFO  (qtp1930404505-7162) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@718d27e
   [junit4]   2> 827350 INFO  (qtp1930404505-7162) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4de96d17[collection1] main]
   [junit4]   2> 827350 INFO  (qtp1930404505-7162) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827351 INFO  (searcherExecutor-2940-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4de96d17[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2)))}
   [junit4]   2> 827351 INFO  (qtp1930404505-7162) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
   [junit4]   2> 827353 INFO  (qtp1930404505-7167) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[18 (1549277146803863552)]} 0 1
   [junit4]   2> 827354 INFO  (qtp1695755482-7135) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[19 (1549277146804912128)]} 0 1
   [junit4]   2> 827356 INFO  (qtp1765772251-7146) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[20 (1549277146807009280)]} 0 1
   [junit4]   2> 827356 INFO  (qtp1930404505-7168) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[21 (1549277146808057856)]} 0 0
   [junit4]   2> 827357 INFO  (qtp1765772251-7151) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[22 (1549277146808057856)]} 0 0
   [junit4]   2> 827357 INFO  (qtp1930404505-7169) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[23 (1549277146809106432)]} 0 0
   [junit4]   2> 827357 INFO  (qtp1695755482-7136) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[24 (1549277146809106432)]} 0 0
   [junit4]   2> 827357 INFO  (qtp1765772251-7152) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[25 (1549277146809106432)]} 0 0
   [junit4]   2> 827358 INFO  (qtp1930404505-7162) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[26 (1549277146810155008)]} 0 0
   [junit4]   2> 827358 INFO  (qtp1695755482-7137) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[27 (1549277146810155008)]} 0 0
   [junit4]   2> 827358 INFO  (qtp1765772251-7153) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[28 (1549277146810155008)]} 0 0
   [junit4]   2> 827358 INFO  (qtp1695755482-7130) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827359 INFO  (qtp1695755482-7130) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23a46954
   [junit4]   2> 827361 INFO  (qtp1695755482-7130) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@9bde95c[collection1] main]
   [junit4]   2> 827361 INFO  (qtp1695755482-7130) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827361 INFO  (searcherExecutor-2918-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@9bde95c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2) Uninverting(_2(7.0.0):C3)))}
   [junit4]   2> 827361 INFO  (qtp1695755482-7130) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827362 INFO  (qtp1765772251-7146) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827362 INFO  (qtp1765772251-7146) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@650930c7
   [junit4]   2> 827363 INFO  (qtp1765772251-7146) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2bbda722[collection1] main]
   [junit4]   2> 827363 INFO  (qtp1765772251-7146) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827364 INFO  (searcherExecutor-2929-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2bbda722[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C5) Uninverting(_2(7.0.0):C4)))}
   [junit4]   2> 827364 INFO  (qtp1765772251-7146) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827364 INFO  (qtp1930404505-7167) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827364 INFO  (qtp1930404505-7167) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@718d27e
   [junit4]   2> 827365 INFO  (qtp1930404505-7167) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3bb2f7e8[collection1] main]
   [junit4]   2> 827365 INFO  (qtp1930404505-7167) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827366 INFO  (searcherExecutor-2940-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@3bb2f7e8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2) Uninverting(_2(7.0.0):C4)))}
   [junit4]   2> 827366 INFO  (qtp1930404505-7167) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
   [junit4]   2> 827367 INFO  (qtp1930404505-7168) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[29 (1549277146818543616)]} 0 1
   [junit4]   2> 827368 INFO  (qtp1695755482-7135) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[30 (1549277146819592192)]} 0 0
   [junit4]   2> 827369 INFO  (qtp1765772251-7151) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[31 (1549277146821689344)]} 0 0
   [junit4]   2> 827370 INFO  (qtp1930404505-7169) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[32 (1549277146822737920)]} 0 0
   [junit4]   2> 827370 INFO  (qtp1930404505-7162) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[33 (1549277146822737921)]} 0 0
   [junit4]   2> 827371 INFO  (qtp1695755482-7136) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[34 (1549277146822737920)]} 0 0
   [junit4]   2> 827371 INFO  (qtp1765772251-7152) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[35 (1549277146823786496)]} 0 0
   [junit4]   2> 827371 INFO  (qtp1930404505-7167) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[36 (1549277146823786496)]} 0 0
   [junit4]   2> 827372 INFO  (qtp1695755482-7137) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[37 (1549277146824835072)]} 0 0
   [junit4]   2> 827372 INFO  (qtp1765772251-7153) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[38 (1549277146824835072)]} 0 0
   [junit4]   2> 827372 INFO  (qtp1930404505-7168) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[39 (1549277146824835072)]} 0 0
   [junit4]   2> 827372 INFO  (qtp1695755482-7130) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[40 (1549277146824835073)]} 0 0
   [junit4]   2> 827373 INFO  (qtp1695755482-7135) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827373 INFO  (qtp1695755482-7135) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23a46954
   [junit4]   2> 827374 INFO  (qtp1695755482-7135) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4abf6531[collection1] main]
   [junit4]   2> 827374 INFO  (qtp1695755482-7135) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827374 INFO  (searcherExecutor-2918-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4abf6531[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2) Uninverting(_2(7.0.0):C3) Uninverting(_3(7.0.0):C4)))}
   [junit4]   2> 827375 INFO  (qtp1695755482-7135) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827375 INFO  (qtp1765772251-7146) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827375 INFO  (qtp1765772251-7146) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@650930c7
   [junit4]   2> 827377 INFO  (qtp1765772251-7146) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@f7436b4[collection1] main]
   [junit4]   2> 827377 INFO  (qtp1765772251-7146) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827377 INFO  (searcherExecutor-2929-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@f7436b4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C5) Uninverting(_2(7.0.0):C4) Uninverting(_3(7.0.0):C3)))}
   [junit4]   2> 827378 INFO  (qtp1765772251-7146) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827378 INFO  (qtp1930404505-7169) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827378 INFO  (qtp1930404505-7169) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@718d27e
   [junit4]   2> 827379 INFO  (qtp1930404505-7169) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@556baf04[collection1] main]
   [junit4]   2> 827379 INFO  (qtp1930404505-7169) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827379 INFO  (searcherExecutor-2940-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@556baf04[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2) Uninverting(_2(7.0.0):C4) Uninverting(_3(7.0.0):C5)))}
   [junit4]   2> 827380 INFO  (qtp1930404505-7169) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 1
   [junit4]   2> 827381 INFO  (qtp1765772251-7151) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[41 (1549277146833223680)]} 0 1
   [junit4]   2> 827382 INFO  (qtp1930404505-7162) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[42 (1549277146834272256)]} 0 0
   [junit4]   2> 827384 INFO  (qtp1695755482-7136) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[43 (1549277146835320832)]} 0 1
   [junit4]   2> 827385 INFO  (qtp1765772251-7152) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[44 (1549277146838466560)]} 0 0
   [junit4]   2> 827385 INFO  (qtp1930404505-7167) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[45 (1549277146838466560)]} 0 0
   [junit4]   2> 827385 INFO  (qtp1695755482-7137) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[46 (1549277146838466560)]} 0 0
   [junit4]   2> 827386 INFO  (qtp1765772251-7153) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[47 (1549277146839515136)]} 0 0
   [junit4]   2> 827386 INFO  (qtp1930404505-7168) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[48 (1549277146839515136)]} 0 0
   [junit4]   2> 827387 INFO  (qtp1695755482-7130) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[49 (1549277146840563712)]} 0 0
   [junit4]   2> 827387 INFO  (qtp1765772251-7146) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[50 (1549277146840563712)]} 0 0
   [junit4]   2> 827388 INFO  (qtp1930404505-7168) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[51 (1549277146841612288)]} 0 0
   [junit4]   2> 827388 INFO  (qtp1695755482-7130) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[52 (1549277146841612288)]} 0 0
   [junit4]   2> 827388 INFO  (qtp1765772251-7151) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[53 (1549277146841612288)]} 0 0
   [junit4]   2> 827389 INFO  (qtp1930404505-7162) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[54 (1549277146842660864)]} 0 0
   [junit4]   2> 827389 INFO  (qtp1695755482-7136) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827389 INFO  (qtp1695755482-7136) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23a46954
   [junit4]   2> 827390 INFO  (qtp1695755482-7136) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@194dbe02[collection1] main]
   [junit4]   2> 827391 INFO  (qtp1695755482-7136) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827391 INFO  (searcherExecutor-2918-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@194dbe02[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2) Uninverting(_2(7.0.0):C3) Uninverting(_3(7.0.0):C4) Uninverting(_4(7.0.0):C4)))}
   [junit4]   2> 827391 INFO  (qtp1695755482-7136) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827392 INFO  (qtp1765772251-7152) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827392 INFO  (qtp1765772251-7152) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@650930c7
   [junit4]   2> 827393 INFO  (qtp1765772251-7152) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@709a108c[collection1] main]
   [junit4]   2> 827393 INFO  (qtp1765772251-7152) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827394 INFO  (searcherExecutor-2929-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@709a108c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C5) Uninverting(_2(7.0.0):C4) Uninverting(_3(7.0.0):C3) Uninverting(_4(7.0.0):C5)))}
   [junit4]   2> 827394 INFO  (qtp1765772251-7152) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827394 INFO  (qtp1930404505-7167) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827394 INFO  (qtp1930404505-7167) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@718d27e
   [junit4]   2> 827396 INFO  (qtp1930404505-7167) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a41f12f[collection1] main]
   [junit4]   2> 827397 INFO  (qtp1930404505-7167) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827397 INFO  (searcherExecutor-2940-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6a41f12f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2) Uninverting(_2(7.0.0):C4) Uninverting(_3(7.0.0):C5) Uninverting(_4(7.0.0):C5)))}
   [junit4]   2> 827397 INFO  (qtp1930404505-7167) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827399 INFO  (qtp1930404505-7169) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[55 (1549277146852098048)]} 0 1
   [junit4]   2> 827400 INFO  (qtp1695755482-7137) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[56 (1549277146853146624)]} 0 0
   [junit4]   2> 827401 INFO  (qtp1765772251-7153) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[57 (1549277146854195200)]} 0 0
   [junit4]   2> 827401 INFO  (qtp1695755482-7135) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827401 INFO  (qtp1695755482-7135) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@23a46954
   [junit4]   2> 827403 INFO  (qtp1695755482-7135) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2449c49d[collection1] main]
   [junit4]   2> 827403 INFO  (qtp1695755482-7135) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827403 INFO  (searcherExecutor-2918-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2449c49d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2) Uninverting(_2(7.0.0):C3) Uninverting(_3(7.0.0):C4) Uninverting(_4(7.0.0):C4) Uninverting(_5(7.0.0):C1)))}
   [junit4]   2> 827403 INFO  (qtp1695755482-7135) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827404 INFO  (qtp1765772251-7146) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827404 INFO  (qtp1765772251-7146) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@650930c7
   [junit4]   2> 827405 INFO  (qtp1765772251-7146) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@45520b0a[collection1] main]
   [junit4]   2> 827405 INFO  (qtp1765772251-7146) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827406 INFO  (searcherExecutor-2929-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@45520b0a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C5) Uninverting(_2(7.0.0):C4) Uninverting(_3(7.0.0):C3) Uninverting(_4(7.0.0):C5) Uninverting(_5(7.0.0):C1)))}
   [junit4]   2> 827406 INFO  (qtp1765772251-7146) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827406 INFO  (qtp1930404505-7168) [    ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 827406 INFO  (qtp1930404505-7168) [    ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@718d27e
   [junit4]   2> 827408 INFO  (qtp1930404505-7168) [    ] o.a.s.s.SolrIndexSearcher Opening [Searcher@7936d186[collection1] main]
   [junit4]   2> 827408 INFO  (qtp1930404505-7168) [    ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
   [junit4]   2> 827408 INFO  (searcherExecutor-2940-thread-1) [    ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7936d186[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C3) Uninverting(_1(7.0.0):C2) Uninverting(_2(7.0.0):C4) Uninverting(_3(7.0.0):C5) Uninverting(_4(7.0.0):C5) Uninverting(_5(7.0.0):C1)))}
   [junit4]   2> 827408 INFO  (qtp1930404505-7168) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 2
   [junit4]   2> 827410 INFO  (qtp1930404505-7162) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[58 (1549277146863632384)]} 0 1
   [junit4]   2> 827411 INFO  (qtp1695755482-7130) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[59 (1549277146864680960)]} 0 0
   [junit4]   2> 827412 INFO  (qtp1765772251-7151) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[60 (1549277146865729536)]} 0 0
   [junit4]   2> 827413 INFO  (qtp1930404505-7167) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[61 (1549277146867826688)]} 0 0
   [junit4]   2> 827413 INFO  (qtp1695755482-7136) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[62 (1549277146867826688)]} 0 0
   [junit4]   2> 827413 INFO  (qtp1765772251-7152) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update params={wt=javabin&version=2}{add=[63 (1549277146867826688)]} 0 0
   [junit4]   2> 827414 INFO  (qtp1930404505-7169) [    ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=/solr path=/update p

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

{date=date_dt&super_s=super_s&distrib=false&fl=id&fl=score&shards.purpose=1048580&noexist=noexist_s&fsv=true&shard.url=127.0.0.1:39087/solr/collection1&where_s=where_s&Z_num_i=Z_num_i&num_is=num_is&wt=javabin&multi_ss=multi_ss&_facet_={}&val_b=val_b&start=0&cat_s=cat_s&rows=0&version=2&num_d=num_d&q=*:*&json.facet={cat0:{type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:0},cat1:{type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:1},catDef:{type:terms,+field:cat_s,+sort:'count+desc',+limit:1,+overrequest:-1},catBig:{type:terms,+field:cat_s,+sort:'count+desc',+offset:1,+limit:2147483647,+overrequest:2147483647}}&num_i=num_i&sparse_s=sparse_s&NOW=1477505843986&isShard=true&num_fs=num_fs} hits=2 status=0 QTime=0
   [junit4]   2> 847438 INFO  (qtp1695755482-7135) [    ] o.a.s.c.S.Request [collection1]  webapp=/solr path=/select params={date=date_dt&super_s=super_s&indent=true&noexist=noexist_s&val_b=val_b&cat_s=cat_s&rows=0&version=2.2&num_d=num_d&shards=127.0.0.1:33092/solr/collection1,127.0.0.1:42257/solr/collection1,127.0.0.1:39087/solr/collection1&shards=debugQuery&shards=false&q=*:*&json.facet={cat0:{type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:0},cat1:{type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:1},catDef:{type:terms,+field:${cat_s},+sort:'count+desc',+limit:1,+overrequest:-1},catBig:{type:terms,+field:${cat_s},+sort:'count+desc',+offset:1,+limit:2147483647,+overrequest:2147483647}}&num_i=num_i&sparse_s=sparse_s&where_s=where_s&Z_num_i=Z_num_i&num_is=num_is&wt=json&multi_ss=multi_ss&num_fs=num_fs} hits=6 status=0 QTime=1
   [junit4]   2> 847438 ERROR (TEST-TestJsonFacets.testStatsDistrib-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.SolrTestCaseHS query failed JSON validation. error=mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
   [junit4]   2>  expected =facets=={ count:6, cat0:{ buckets:[ {val:A,count:2} ] }, cat1:{ buckets:[ {val:B,count:3} ] }, catDef:{ buckets:[ {val:B,count:3} ] }, catBig:{ buckets:[ {val:A,count:2} ] }}
   [junit4]   2>  response = {
   [junit4]   2>   "responseHeader":{
   [junit4]   2>     "status":0,
   [junit4]   2>     "QTime":1},
   [junit4]   2>   "response":{"numFound":6,"start":0,"maxScore":1.0,"docs":[]
   [junit4]   2>   },
   [junit4]   2>   "facets":{
   [junit4]   2>     "count":6,
   [junit4]   2>     "catDef":{
   [junit4]   2>       "buckets":[{
   [junit4]   2>           "val":"B",
   [junit4]   2>           "count":3}]},
   [junit4]   2>     "cat0":{
   [junit4]   2>       "buckets":[{
   [junit4]   2>           "val":"B",
   [junit4]   2>           "count":3}]},
   [junit4]   2>     "cat1":{
   [junit4]   2>       "buckets":[{
   [junit4]   2>           "val":"B",
   [junit4]   2>           "count":3}]},
   [junit4]   2>     "catBig":{
   [junit4]   2>       "buckets":[{
   [junit4]   2>           "val":"A",
   [junit4]   2>           "count":2}]}}}
   [junit4]   2> 
   [junit4]   2> 847438 INFO  (TEST-TestJsonFacets.testStatsDistrib-seed#[7AB5CCD1A6FA4C6D]) [    ] o.a.s.SolrTestCaseJ4 ###Ending testStatsDistrib {p0=UIF}
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestJsonFacets -Dtests.method=testStatsDistrib -Dtests.seed=7AB5CCD1A6FA4C6D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-MR -Dtests.timezone=Etc/GMT+1 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   0.33s J2 | TestJsonFacets.testStatsDistrib {p0=UIF} <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: mismatch: 'A'!='B' @ facets/cat0/buckets/[0]/val
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([7AB5CCD1A6FA4C6D:A67565A7CB7BE8A7]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseHS.matchJSON(SolrTestCaseHS.java:161)
   [junit4]    > 	at org.apache.solr.SolrTestCaseHS.assertJQ(SolrTestCaseHS.java:143)
   [junit4]    > 	at org.apache.solr.SolrTestCaseHS$Client$Tester.assertJQ(SolrTestCaseHS.java:255)
   [junit4]    > 	at org.apache.solr.SolrTestCaseHS$Client.testJQ(SolrTestCaseHS.java:296)
   [junit4]    > 	at org.apache.solr.search.facet.TestJsonFacets.doStatsTemplated(TestJsonFacets.java:1152)
   [junit4]    > 	at org.apache.solr.search.facet.TestJsonFacets.doStats(TestJsonFacets.java:361)
   [junit4]    > 	at org.apache.solr.search.facet.TestJsonFacets.testStatsDistrib(TestJsonFacets.java:322)
   [junit4]    > 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(java.base@9-ea/Native Method)
   [junit4]    > 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(java.base@9-ea/NativeMethodAccessorImpl.java:62)
   [junit4]    > 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(java.base@9-ea/DelegatingMethodAccessorImpl.java:43)
   [junit4]    > 	at java.lang.Thread.run(java.base@9-ea/Thread.java:843)
   [junit4]   2> 847440 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.e.j.s.ServerConnector Stopped ServerConnector@5afc34ed{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 847441 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1203540890
   [junit4]   2> 847461 INFO  (coreCloseExecutor-2945-thread-1) [    ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@1ed0c689
   [junit4]   2> 847475 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6942f2c0{/solr,null,UNAVAILABLE}
   [junit4]   2> 847476 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.e.j.s.ServerConnector Stopped ServerConnector@4cf44595{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 847476 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1063705791
   [junit4]   2> 847477 INFO  (coreCloseExecutor-2947-thread-1) [    ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@303a0bf9
   [junit4]   2> 847500 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@c8466ae{/solr,null,UNAVAILABLE}
   [junit4]   2> 847501 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.e.j.s.ServerConnector Stopped ServerConnector@a643fe7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
   [junit4]   2> 847501 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=595389083
   [junit4]   2> 847520 INFO  (coreCloseExecutor-2949-thread-1) [    ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@2c8a0b80
   [junit4]   2> 847551 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@704efae8{/solr,null,UNAVAILABLE}
   [junit4]   2> 847552 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 847552 INFO  (SUITE-TestJsonFacets-seed#[7AB5CCD1A6FA4C6D]-worker) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1181109647
   [junit4]   2> 847552 INFO  (coreCloseExecutor-2951-thread-1) [    ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@6e854797
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J2/temp/solr.search.facet.TestJsonFacets_7AB5CCD1A6FA4C6D-001
   [junit4]   2> NOTE: test params are: codec=Lucene70, sim=RandomSimilarity(queryNorm=false): {}, locale=fr-MR, timezone=Etc/GMT+1
   [junit4]   2> NOTE: Linux 4.4.0-42-generic amd64/Oracle Corporation 9-ea (64-bit)/cpus=12,threads=1,free=89188496,total=530579456
   [junit4]   2> NOTE: All tests run in this JVM: [TestUseDocValuesAsStored2, TestOverriddenPrefixQueryForCustomFieldType, TestMiniSolrCloudCluster, ClassificationUpdateProcessorFactoryTest, FullSolrCloudDistribCmdsTest, PrimitiveFieldTypeTest, SuggesterTSTTest, DocValuesMultiTest, TestMergePolicyConfig, HardAutoCommitTest, CursorMarkTest, TestConfigSetsAPIExclusivity, SynonymTokenizerTest, TestDistribDocBasedVersion, TestScoreJoinQPNoScore, DirectUpdateHandlerOptimizeTest, XmlUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, DateFieldTest, ShowFileRequestHandlerTest, TestWriterPerf, FieldAnalysisRequestHandlerTest, TestTolerantUpdateProcessorCloud, UpdateParamsTest, ChangedSchemaMergeTest, BlockJoinFacetRandomTest, NoCacheHeaderTest, RuleEngineTest, PreAnalyzedFieldTest, DebugComponentTest, TestCollapseQParserPlugin, TestReloadDeadlock, TermVectorComponentDistributedTest, BadIndexSchemaTest, TestCloudManagedSchema, DistributedFacetPivotSmallAdvancedTest, FieldMutatingUpdateProcessorTest, JSONWriterTest, TestStandardQParsers, TestReloadAndDeleteDocs, UUIDUpdateProcessorFallbackTest, TestCloudInspectUtil, HttpPartitionTest, TestManagedSynonymFilterFactory, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestAnalyzeInfixSuggestions, CollectionsAPISolrJTest, CleanupOldIndexTest, TestLMDirichletSimilarityFactory, LeaderElectionTest, TestSolrDynamicMBean, TestHashQParserPlugin, CdcrRequestHandlerTest, TestCoreDiscovery, DistributedQueueTest, DirectoryFactoryTest, SolrXmlInZkTest, TestManagedResourceStorage, TestSortingResponseWriter, TestDistributedMissingSort, OverseerTest, CollectionStateFormat2Test, TestHdfsCloudBackupRestore, ConnectionManagerTest, VersionInfoTest, HdfsRecoveryZkTest, TestSQLHandlerNonCloud, SliceStateTest, RAMDirectoryFactoryTest, ExternalFileFieldSortTest, LeaderFailureAfterFreshStartTest, HdfsCollectionsAPIDistributedZkTest, TestRebalanceLeaders, TestCloudRecovery, OverriddenZkACLAndCredentialsProvidersTest, TestDocBasedVersionConstraints, TestGroupingSearch, CloudMLTQParserTest, DeleteInactiveReplicaTest, BasicDistributedZk2Test, TestFastOutputStream, EnumFieldTest, TestUtils, TestJsonFacets]
   [junit4] Completed [269/644 (1!)] on J2 in 22.39s, 48 tests, 6 errors <<< FAILURES!

[...truncated 51103 lines...]