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/19 00:04:33 UTC
[JENKINS-EA] Lucene-Solr-6.x-Linux (32bit/jdk-9-ea+140) - Build #
1982 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1982/
Java: 32bit/jdk-9-ea+140 -client -XX:+UseConcMarkSweepGC
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([454374B9A6CE2DDA:CD174B6308324022]: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:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java: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:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java: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 11362 lines...]
[junit4] Suite: org.apache.solr.handler.component.SpellCheckComponentTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.SpellCheckComponentTest_454374B9A6CE2DDA-001/init-core-data-001
[junit4] 2> 728272 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 728273 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 728274 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 728350 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 728371 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 728440 WARN (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead.
[junit4] 2> 728443 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 728464 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true
[junit4] 2> 728468 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 728531 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 728550 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 728619 WARN (coreLoadExecutor-3592-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> 728620 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 728641 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1
[junit4] 2> 728641 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.SpellCheckComponentTest_454374B9A6CE2DDA-001/init-core-data-001/]
[junit4] 2> 728642 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=16.908203125, floorSegmentMB=0.9697265625, forceMergeDeletesPctAllowed=10.138743682106936, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0
[junit4] 2> 728646 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 728646 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 728646 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=37, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6508751420739227]
[junit4] 2> 728646 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@12c39f6[collection1] main]
[junit4] 2> 728647 WARN (coreLoadExecutor-3592-thread-1) [ ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 728647 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 728682 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default
[junit4] 2> 728715 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: default_teststop
[junit4] 2> 728715 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=teststop}
[junit4] 2> 728715 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct_lowerfilt,classname=solr.DirectSolrSpellChecker,minQueryLength=3,maxQueryFrequency=100.0,field=lowerfilt}
[junit4] 2> 728742 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold
[junit4] 2> 728742 INFO (coreLoadExecutor-3592-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> 728742 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: threshold_direct
[junit4] 2> 728776 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: multipleFields
[junit4] 2> 728902 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: freq
[junit4] 2> 728943 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Registering newSearcher listener for spellchecker: fqcn
[junit4] 2> 728943 INFO (coreLoadExecutor-3592-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 728943 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 728944 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 728945 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
[junit4] 2> 728950 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 728951 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 728951 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 728951 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
[junit4] 2> 728951 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 728951 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
[junit4] 2> 728951 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 728952 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 728952 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 728952 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 728952 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 728953 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 728953 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 728953 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
[junit4] 2> 728953 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 728953 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 728953 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 728953 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 728953 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 728955 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 728956 WARN (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.c.SolrCore [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2
[junit4] 2> 728956 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 728958 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1f8364[collection1] main]
[junit4] 2> 728958 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 728958 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 728960 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 728961 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 728961 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@12c39f6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 728961 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 728963 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default_teststop
[junit4] 2> 728965 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 728965 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct_lowerfilt
[junit4] 2> 728965 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 728965 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold
[junit4] 2> 728966 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: threshold_direct
[junit4] 2> 728966 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 728968 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 728970 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 728971 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 728972 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 728980 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 728980 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1f8364[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):c10)))}
[junit4] 2> 728980 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 26
[junit4] 2> 728981 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] 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> 728981 ERROR (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] 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> 728981 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> 728982 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 728982 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 728982 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 728982 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 728982 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@197fc66[collection1] main]
[junit4] 2> 728982 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 728982 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 729021 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 729072 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 729124 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 729124 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 729178 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 729228 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 729280 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@197fc66[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 729280 INFO (TEST-SpellCheckComponentTest.test-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 297
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SpellCheckComponentTest -Dtests.method=test -Dtests.seed=454374B9A6CE2DDA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=eu-ES -Dtests.timezone=Indian/Maldives -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.34s J1 | SpellCheckComponentTest.test <<<
[junit4] > Throwable #1: java.lang.RuntimeException: List size mismatch @ spellcheck/suggestions
[junit4] > at __randomizedtesting.SeedInfo.seed([454374B9A6CE2DDA:CD174B6308324022]: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> 729283 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCorrectSpelling
[junit4] 2> 729283 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 729283 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 729284 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 729284 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 729284 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 729284 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 729285 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 729285 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 729285 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 729285 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 729285 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 729285 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 729291 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@eb4dab[collection1] main]
[junit4] 2> 729291 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 729291 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 729474 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 729517 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 729705 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 729705 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 729838 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 730002 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 730155 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@eb4dab[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(6.3.0):c10)))}
[junit4] 2> 730155 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 869
[junit4] 2> 730156 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] 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> 730156 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] 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> 730160 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] 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=3
[junit4] 2> 730160 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCorrectSpelling
[junit4] 2> 730160 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 730160 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 730160 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 730160 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 730161 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@467bcc[collection1] main]
[junit4] 2> 730161 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 730161 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 730207 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 730262 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 730318 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 730318 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 730385 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 730449 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 730495 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@467bcc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 730495 INFO (TEST-SpellCheckComponentTest.testCorrectSpelling-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 335
[junit4] 2> 730497 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReloadOnStart
[junit4] 2> 730497 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 730498 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 730498 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 730498 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 730498 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 730502 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 730502 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 730502 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 730503 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 730503 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 730503 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 730503 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 730506 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@179db73[collection1] main]
[junit4] 2> 730506 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 730506 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 730665 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 730726 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 730871 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 730871 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 731058 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 731215 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 731362 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@179db73[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.3.0):c10)))}
[junit4] 2> 731363 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 859
[junit4] 2> 731363 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 731363 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 731364 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 731367 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1dabb24[collection1] main]
[junit4] 2> 731367 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 731367 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 731541 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 731585 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 731777 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 731778 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 731912 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 732048 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 732222 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1dabb24[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(6.3.0):c10/1:delGen=1) Uninverting(_3(6.3.0):c1)))}
[junit4] 2> 732222 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 858
[junit4] 2> 732452 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] 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=230
[junit4] 2> 732454 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 732464 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReloadOnStart
[junit4] 2> 732465 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 732465 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 732465 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 732465 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 732465 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2f4af2[collection1] main]
[junit4] 2> 732465 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 732465 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 732546 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 732608 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 732662 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 732662 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 732727 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 732781 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 732864 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2f4af2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 732864 INFO (TEST-SpellCheckComponentTest.testReloadOnStart-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 399
[junit4] 2> 732866 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPerDictionary
[junit4] 2> 732866 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 732867 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 732867 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 732867 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 732867 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 732867 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 732868 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 732868 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 732868 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 732868 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 732868 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 732868 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 732872 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@127a67b[collection1] main]
[junit4] 2> 732872 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 732872 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 733039 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 733094 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 733248 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 733248 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 733423 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 733587 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 733739 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@127a67b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_4(6.3.0):c10)))}
[junit4] 2> 733739 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 870
[junit4] 2> 733740 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] 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> 733740 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPerDictionary
[junit4] 2> 733740 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 733740 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 733740 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 733740 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 733741 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@eb79e8[collection1] main]
[junit4] 2> 733741 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 733741 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 733793 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 733869 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 733926 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 733927 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 733984 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 734043 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 734097 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@eb79e8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 734097 INFO (TEST-SpellCheckComponentTest.testPerDictionary-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 356
[junit4] 2> 734099 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRebuildOnCommit
[junit4] 2> 734099 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 734099 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 734099 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 734100 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 734100 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 734100 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 734100 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 734100 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 734101 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 734101 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 734101 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 734101 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 734104 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@6a8e3b[collection1] main]
[junit4] 2> 734104 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 734104 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 734274 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 734335 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 734458 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 734458 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 734593 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 734764 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 734940 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6a8e3b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.3.0):c10)))}
[junit4] 2> 734940 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 839
[junit4] 2> 734942 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:lucenejavt&qt=spellCheckCompRH&spellcheck=true&wt=xml} hits=0 status=0 QTime=1
[junit4] 2> 734942 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[11231]} 0 0
[junit4] 2> 734942 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 734942 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 734945 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@18749ac[collection1] main]
[junit4] 2> 734946 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 734946 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 735098 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 735155 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 735307 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 735307 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 735454 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 735649 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 735841 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@18749ac[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_5(6.3.0):c10) Uninverting(_6(6.3.0):c1)))}
[junit4] 2> 735842 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 899
[junit4] 2> 735843 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=lowerfilt:lucenejavt&qt=spellCheckCompRH&spellcheck=true&wt=xml} hits=0 status=0 QTime=902
[junit4] 2> 735843 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRebuildOnCommit
[junit4] 2> 735843 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 735843 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 735843 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 735843 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 735844 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ee1578[collection1] main]
[junit4] 2> 735844 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 735844 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 735913 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 735986 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 736042 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 736042 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 736088 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 736132 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 736190 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1ee1578[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 736191 INFO (TEST-SpellCheckComponentTest.testRebuildOnCommit-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 347
[junit4] 2> 736192 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNumericQuery
[junit4] 2> 736193 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 736193 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 736193 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 736193 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 736194 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 736194 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 736194 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 736194 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 736194 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 736195 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 736195 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 736195 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 736200 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1346c99[collection1] main]
[junit4] 2> 736200 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 736200 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 736371 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 736409 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 736549 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 736549 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 736691 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 736835 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 736992 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1346c99[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_7(6.3.0):c10)))}
[junit4] 2> 736992 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 797
[junit4] 2> 736993 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] 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> 736993 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testNumericQuery
[junit4] 2> 736994 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 736994 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 736994 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 736994 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 736994 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@16c483c[collection1] main]
[junit4] 2> 736994 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 736994 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 737055 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 737120 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 737175 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 737175 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 737223 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 737275 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 737341 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@16c483c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 737341 INFO (TEST-SpellCheckComponentTest.testNumericQuery-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 347
[junit4] 2> 737343 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCollate
[junit4] 2> 737343 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 737343 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 737344 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 737344 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 737344 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 737344 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 737347 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 737347 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 737347 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 737347 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 737348 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 737348 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 737350 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@165211e[collection1] main]
[junit4] 2> 737351 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 737351 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 737518 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 737568 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 737762 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 737762 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 737961 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 738099 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 738235 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@165211e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_8(6.3.0):c10)))}
[junit4] 2> 738235 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 887
[junit4] 2> 738467 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] 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=231
[junit4] 2> 738469 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] 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> 738470 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] 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=1
[junit4] 2> 738470 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] 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> 738470 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCollate
[junit4] 2> 738471 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 738471 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 738471 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 738471 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 738471 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@844f73[collection1] main]
[junit4] 2> 738471 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 738478 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 738544 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 738620 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 738683 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 738683 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 738745 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 738828 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 738873 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@844f73[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 738873 INFO (TEST-SpellCheckComponentTest.testCollate-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 402
[junit4] 2> 738875 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testExtendedResultsCount
[junit4] 2> 738875 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 738875 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 738875 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 738876 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 738876 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 738876 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 738876 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 738876 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 738876 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 738877 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 738877 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 738877 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 738880 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@8b4d8[collection1] main]
[junit4] 2> 738880 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 738880 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 739035 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 739081 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 739185 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 739185 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 739372 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 739515 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 739688 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@8b4d8[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_9(6.3.0):c10)))}
[junit4] 2> 739689 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 811
[junit4] 2> 739850 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] 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=160
[junit4] 2> 739850 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] 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> 739850 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExtendedResultsCount
[junit4] 2> 739850 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 739850 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 739851 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 739851 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 739851 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@139e3f[collection1] main]
[junit4] 2> 739851 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 739851 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 739893 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 739941 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 739986 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 739986 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 740036 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 740095 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 740149 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@139e3f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 740149 INFO (TEST-SpellCheckComponentTest.testExtendedResultsCount-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 298
[junit4] 2> 740150 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRelativeIndexDirLocation
[junit4] 2> 740151 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 740151 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 740151 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 740151 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 740152 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 740152 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 740152 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 740152 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 740152 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 740153 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 740153 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 740153 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 740156 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@14fee7e[collection1] main]
[junit4] 2> 740156 INFO (TEST-SpellCheckComponentTest.testRelativeIndexDirLocation-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 740156 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 740327 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 740408 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 740593 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 740593 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 740769 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 740946 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Buildi
[...truncated too long message...]
2,
[junit4] 2> "timestamp":"2016-10-18T23:28:25.411Z"},
[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-18T23:28:25.412Z"},
[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-18T23:28:25.414Z"},
[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-18T23:28:25.410Z"},
[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-18T23:28:25.411Z"}]
[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> 744854 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[454374B9A6CE2DDA]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMaximumResultsForSuggest
[junit4] 2> 744854 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 744854 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{deleteByQuery=*:*} 0 0
[junit4] 2> 744854 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 744854 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@e6c2cd
[junit4] 2> 744854 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[454374B9A6CE2DDA]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1c80471[collection1] main]
[junit4] 2> 744855 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 744855 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default
[junit4] 2> 744915 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: default_teststop
[junit4] 2> 744964 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold
[junit4] 2> 745018 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: threshold_direct
[junit4] 2> 745018 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: multipleFields
[junit4] 2> 745074 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: freq
[junit4] 2> 745141 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Building spell index for spell checker: fqcn
[junit4] 2> 745182 INFO (searcherExecutor-3593-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@1c80471[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 745182 INFO (TEST-SpellCheckComponentTest.testMaximumResultsForSuggest-seed#[454374B9A6CE2DDA]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 327
[junit4] 2> 745183 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 745183 INFO (SUITE-SpellCheckComponentTest-seed#[454374B9A6CE2DDA]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=19348377
[junit4] 2> 745183 INFO (coreCloseExecutor-3597-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@150eda2
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J1/temp/solr.handler.component.SpellCheckComponentTest_454374B9A6CE2DDA-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {start1=Lucene50(blocksize=128), range_facet_l_dv=Lucene50(blocksize=128), multiDefault=PostingsFormat(name=MockRandom), intDefault=PostingsFormat(name=LuceneFixedGap), range_facet_l=PostingsFormat(name=LuceneFixedGap), lowerfilt1and2=PostingsFormat(name=MockRandom), end4=Lucene50(blocksize=128), end3=PostingsFormat(name=MockRandom), end2=Lucene50(blocksize=128), end1=PostingsFormat(name=LuceneFixedGap), lowerfilt=PostingsFormat(name=MockRandom), gram1=PostingsFormat(name=LuceneFixedGap), id=Lucene50(blocksize=128), range_facet_i_dv=PostingsFormat(name=LuceneFixedGap), gram2=Lucene50(blocksize=128), gram3=PostingsFormat(name=MockRandom), start3=Lucene50(blocksize=128), gram4=Lucene50(blocksize=128), start2=PostingsFormat(name=MockRandom), word=PostingsFormat(name=LuceneFixedGap), timestamp=PostingsFormat(name=LuceneFixedGap), start4=PostingsFormat(name=LuceneFixedGap)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=819, maxMBSortInHeap=7.013202061334563, sim=ClassicSimilarity, locale=eu-ES, timezone=Indian/Maldives
[junit4] 2> NOTE: Linux 4.4.0-42-generic i386/Oracle Corporation 9-ea (32-bit)/cpus=12,threads=1,free=88909872,total=260489216
[junit4] 2> NOTE: All tests run in this JVM: [HdfsDirectoryTest, ConfigSetsAPITest, UpdateRequestProcessorFactoryTest, JavabinLoaderTest, CollectionReloadTest, MergeStrategyTest, LoggingHandlerTest, TestCollectionAPI, CopyFieldTest, TestSweetSpotSimilarityFactory, TestUpdate, BlockJoinFacetDistribTest, HdfsTlogReplayBufferedWhileIndexingTest, TestConfigSetsAPIZkFailure, TestLRUCache, TestSQLHandler, DeleteStatusTest, TestTrieFacet, ConjunctionSolrSpellCheckerTest, SuggestComponentContextFilterQueryTest, ShardRoutingTest, TestExactStatsCache, TestFuzzyAnalyzedSuggestions, TestOrdValues, TestSolrFieldCacheMBean, TermsComponentTest, StressHdfsTest, TestFastOutputStream, TestJsonRequest, TestOnReconnectListenerSupport, TestGeoJSONResponseWriter, AliasIntegrationTest, IndexSchemaTest, ShardRoutingCustomTest, TestSolrConfigHandlerConcurrent, TestWordDelimiterFilterFactory, SpellingQueryConverterTest, TestFilteredDocIdSet, TestCoreContainer, TestDocBasedVersionConstraints, ZkControllerTest, PKIAuthenticationIntegrationTest, HdfsChaosMonkeyNothingIsSafeTest, DistributedFacetPivotWhiteBoxTest, TestSearchPerf, TestSuggestSpellingConverter, TestPartialUpdateDeduplication, TestBlobHandler, TermVectorComponentTest, WrapperMergePolicyFactoryTest, TestLMDirichletSimilarityFactory, DistanceUnitsTest, TestMacroExpander, TestSolrXml, SampleTest, TestManagedSchema, TestFieldCacheSort, DistribJoinFromCollectionTest, ExitableDirectoryReaderTest, TestRecoveryHdfs, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestSurroundQueryParser, CustomCollectionTest, TestRTGBase, DateRangeFieldTest, TestUniqueKeyFieldResource, RollingRestartTest, ClassificationUpdateProcessorFactoryTest, AnalyticsMergeStrategyTest, TestAddFieldRealTimeGet, TestInitParams, TestTrie, CSVRequestHandlerTest, MultiTermTest, SearchHandlerTest, BasicFunctionalityTest, TestCopyFieldCollectionResource, TestRequestStatusCollectionAPI, SolrIndexSplitterTest, LeaderFailureAfterFreshStartTest, TestHashPartitioner, TestRandomCollapseQParserPlugin, TestDistributedGrouping, SortByFunctionTest, TestFileDictionaryLookup, ZkCLITest, HdfsNNFailoverTest, SliceStateTest, CollectionsAPIAsyncDistributedZkTest, TestDynamicFieldResource, SchemaVersionSpecificBehaviorTest, DateMathParserTest, TestSolrCLIRunExample, GraphQueryTest, TestElisionMultitermQuery, TestLeaderElectionWithEmptyReplica, TestSerializedLuceneMatchVersion, WordBreakSolrSpellCheckerTest, DirectSolrSpellCheckerTest, TestInitQParser, RulesTest, SpellCheckComponentTest]
[junit4] Completed [226/640 (1!)] on J1 in 16.93s, 11 tests, 1 error <<< FAILURES!
[...truncated 51291 lines...]
[JENKINS] Lucene-Solr-6.x-Linux (64bit/jdk1.8.0_102) - Build # 1983
- Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-6.x-Linux/1983/
Java: 64bit/jdk1.8.0_102 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.cloud.CloudExitableDirectoryReaderTest.test
Error Message:
No live SolrServers available to handle this request
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request
at __randomizedtesting.SeedInfo.seed([3AE0FEECAF9B0993:B2B4C1360167646B]:0)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:412)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1292)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1062)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1004)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
at org.apache.solr.cloud.CloudExitableDirectoryReaderTest.assertPartialResults(CloudExitableDirectoryReaderTest.java:106)
at org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTimeoutTests(CloudExitableDirectoryReaderTest.java:78)
at org.apache.solr.cloud.CloudExitableDirectoryReaderTest.test(CloudExitableDirectoryReaderTest.java:56)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java: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:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java: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(Thread.java:745)
Build Log:
[...truncated 11179 lines...]
[junit4] Suite: org.apache.solr.cloud.CloudExitableDirectoryReaderTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_3AE0FEECAF9B0993-001/init-core-data-001
[junit4] 2> 502100 INFO (SUITE-CloudExitableDirectoryReaderTest-seed#[3AE0FEECAF9B0993]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 502101 INFO (SUITE-CloudExitableDirectoryReaderTest-seed#[3AE0FEECAF9B0993]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_3AE0FEECAF9B0993-001/tempDir-001
[junit4] 2> 502101 INFO (SUITE-CloudExitableDirectoryReaderTest-seed#[3AE0FEECAF9B0993]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 502101 INFO (Thread-952) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 502101 INFO (Thread-952) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 502201 INFO (SUITE-CloudExitableDirectoryReaderTest-seed#[3AE0FEECAF9B0993]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:46202
[junit4] 2> 502208 INFO (jetty-launcher-587-thread-1) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 502209 INFO (jetty-launcher-587-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@75eb3806{/solr,null,AVAILABLE}
[junit4] 2> 502211 INFO (jetty-launcher-587-thread-1) [ ] o.e.j.s.ServerConnector Started ServerConnector@2c18d975{SSL,[ssl, http/1.1]}{127.0.0.1:33928}
[junit4] 2> 502211 INFO (jetty-launcher-587-thread-2) [ ] o.e.j.s.Server jetty-9.3.8.v20160314
[junit4] 2> 502211 INFO (jetty-launcher-587-thread-1) [ ] o.e.j.s.Server Started @503789ms
[junit4] 2> 502211 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33928}
[junit4] 2> 502211 ERROR (jetty-launcher-587-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 502211 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 502211 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 502211 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 502211 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-19T02:09:30.199Z
[junit4] 2> 502212 INFO (jetty-launcher-587-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5eda10dd{/solr,null,AVAILABLE}
[junit4] 2> 502213 INFO (jetty-launcher-587-thread-2) [ ] o.e.j.s.ServerConnector Started ServerConnector@17bc607{SSL,[ssl, http/1.1]}{127.0.0.1:38600}
[junit4] 2> 502213 INFO (jetty-launcher-587-thread-2) [ ] o.e.j.s.Server Started @503791ms
[junit4] 2> 502213 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 502214 INFO (jetty-launcher-587-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38600}
[junit4] 2> 502214 ERROR (jetty-launcher-587-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 502214 INFO (jetty-launcher-587-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.3.0
[junit4] 2> 502214 INFO (jetty-launcher-587-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 502214 INFO (jetty-launcher-587-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 502214 INFO (jetty-launcher-587-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2016-10-19T02:09:30.202Z
[junit4] 2> 502215 INFO (jetty-launcher-587-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 502220 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 502220 INFO (jetty-launcher-587-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 502220 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46202/solr
[junit4] 2> 502220 INFO (jetty-launcher-587-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:46202/solr
[junit4] 2> 502222 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x157dab35bea0004, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 502231 INFO (jetty-launcher-587-thread-2) [n:127.0.0.1:38600_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:38600_solr
[junit4] 2> 502231 INFO (jetty-launcher-587-thread-1) [n:127.0.0.1:33928_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33928_solr
[junit4] 2> 502231 INFO (jetty-launcher-587-thread-2) [n:127.0.0.1:38600_solr ] o.a.s.c.Overseer Overseer (id=96786380887818245-127.0.0.1:38600_solr-n_0000000000) starting
[junit4] 2> 502232 INFO (zkCallback-598-thread-1-processing-n:127.0.0.1:33928_solr) [n:127.0.0.1:33928_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 502232 INFO (zkCallback-597-thread-1-processing-n:127.0.0.1:38600_solr) [n:127.0.0.1:38600_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 502235 INFO (jetty-launcher-587-thread-2) [n:127.0.0.1:38600_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38600_solr
[junit4] 2> 502235 INFO (zkCallback-598-thread-1-processing-n:127.0.0.1:33928_solr) [n:127.0.0.1:33928_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 502235 INFO (zkCallback-597-thread-1-processing-n:127.0.0.1:38600_solr) [n:127.0.0.1:38600_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 502241 INFO (jetty-launcher-587-thread-1) [n:127.0.0.1:33928_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_3AE0FEECAF9B0993-001/tempDir-001/node1/.
[junit4] 2> 502248 INFO (jetty-launcher-587-thread-2) [n:127.0.0.1:38600_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_3AE0FEECAF9B0993-001/tempDir-001/node2/.
[junit4] 2> 502253 INFO (SUITE-CloudExitableDirectoryReaderTest-seed#[3AE0FEECAF9B0993]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 502311 INFO (qtp842538341-3860) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params async=55147639-d76a-4e62-a144-aaf7eee31f4b&replicationFactor=1&collection.configName=conf&name=exitable&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 502312 INFO (qtp842538341-3860) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={async=55147639-d76a-4e62-a144-aaf7eee31f4b&replicationFactor=1&collection.configName=conf&name=exitable&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 502313 INFO (OverseerThreadFactory-1861-thread-1-processing-n:127.0.0.1:38600_solr) [n:127.0.0.1:38600_solr ] o.a.s.c.CreateCollectionCmd Create collection exitable
[junit4] 2> 502314 INFO (qtp842538341-3865) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 502314 INFO (qtp842538341-3865) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 502420 INFO (qtp842538341-3865) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310&qt=/admin/cores&collection.configName=conf&newCollection=true&name=exitable_shard1_replica1&action=CREATE&numShards=2&collection=exitable&shard=shard1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 502420 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CoreAdminOperation core create command async=55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310&qt=/admin/cores&collection.configName=conf&newCollection=true&name=exitable_shard1_replica1&action=CREATE&numShards=2&collection=exitable&shard=shard1&wt=javabin&version=2
[junit4] 2> 502471 INFO (qtp758957976-3850) [n:127.0.0.1:33928_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={async=55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081&qt=/admin/cores&collection.configName=conf&newCollection=true&name=exitable_shard2_replica1&action=CREATE&numShards=2&collection=exitable&shard=shard2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 502471 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr ] o.a.s.h.a.CoreAdminOperation core create command async=55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081&qt=/admin/cores&collection.configName=conf&newCollection=true&name=exitable_shard2_replica1&action=CREATE&numShards=2&collection=exitable&shard=shard2&wt=javabin&version=2
[junit4] 2> 502473 INFO (qtp842538341-3861) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310
[junit4] 2> 502473 INFO (qtp842538341-3861) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 502574 INFO (zkCallback-597-thread-1-processing-n:127.0.0.1:38600_solr) [n:127.0.0.1:38600_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/exitable/state.json] for collection [exitable] has occurred - updating... (live nodes size: [2])
[junit4] 2> 502574 INFO (zkCallback-598-thread-1-processing-n:127.0.0.1:33928_solr) [n:127.0.0.1:33928_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/exitable/state.json] for collection [exitable] has occurred - updating... (live nodes size: [2])
[junit4] 2> 503316 INFO (qtp842538341-3866) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 503317 INFO (qtp842538341-3866) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 503428 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 x:exitable_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 503436 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 x:exitable_shard1_replica1] o.a.s.s.IndexSchema [exitable_shard1_replica1] Schema name=minimal
[junit4] 2> 503438 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 x:exitable_shard1_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 503439 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 x:exitable_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'exitable_shard1_replica1' using configuration from collection exitable
[junit4] 2> 503439 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.SolrCore [[exitable_shard1_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_3AE0FEECAF9B0993-001/tempDir-001/node2/exitable_shard1_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_3AE0FEECAF9B0993-001/tempDir-001/node2/./exitable_shard1_replica1/data/]
[junit4] 2> 503439 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@593014a8
[junit4] 2> 503447 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 503447 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 503448 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 503448 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 503448 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@49285a07[exitable_shard1_replica1] main]
[junit4] 2> 503449 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 503449 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 503450 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 503450 INFO (searcherExecutor-1866-thread-1-processing-x:exitable_shard1_replica1 r:core_node1 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE n:127.0.0.1:38600_solr s:shard1 c:exitable) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.SolrCore [exitable_shard1_replica1] Registered new searcher Searcher@49285a07[exitable_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 503450 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548582095618572288
[junit4] 2> 503454 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 503454 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 503454 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38600/solr/exitable_shard1_replica1/
[junit4] 2> 503454 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 503454 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.SyncStrategy https://127.0.0.1:38600/solr/exitable_shard1_replica1/ has no replicas
[junit4] 2> 503455 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38600/solr/exitable_shard1_replica1/ shard1
[junit4] 2> 503482 INFO (qtp842538341-3867) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310
[junit4] 2> 503482 INFO (qtp842538341-3867) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 503487 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 x:exitable_shard2_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.3.0
[junit4] 2> 503494 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 x:exitable_shard2_replica1] o.a.s.s.IndexSchema [exitable_shard2_replica1] Schema name=minimal
[junit4] 2> 503497 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 x:exitable_shard2_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 503497 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 x:exitable_shard2_replica1] o.a.s.c.CoreContainer Creating SolrCore 'exitable_shard2_replica1' using configuration from collection exitable
[junit4] 2> 503498 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.SolrCore [[exitable_shard2_replica1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_3AE0FEECAF9B0993-001/tempDir-001/node1/exitable_shard2_replica1], dataDir=[/home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_3AE0FEECAF9B0993-001/tempDir-001/node1/./exitable_shard2_replica1/data/]
[junit4] 2> 503498 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@593014a8
[junit4] 2> 503505 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 503505 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 503506 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 503506 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 503507 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@c01f1e3[exitable_shard2_replica1] main]
[junit4] 2> 503507 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 503507 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 503507 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 503508 INFO (searcherExecutor-1871-thread-1-processing-x:exitable_shard2_replica1 r:core_node2 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE n:127.0.0.1:33928_solr s:shard2 c:exitable) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.SolrCore [exitable_shard2_replica1] Registered new searcher Searcher@c01f1e3[exitable_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 503508 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1548582095679389696
[junit4] 2> 503512 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 503512 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 503512 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:33928/solr/exitable_shard2_replica1/
[junit4] 2> 503512 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 503512 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.SyncStrategy https://127.0.0.1:33928/solr/exitable_shard2_replica1/ has no replicas
[junit4] 2> 503513 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:33928/solr/exitable_shard2_replica1/ shard2
[junit4] 2> 503615 INFO (zkCallback-597-thread-2-processing-n:127.0.0.1:38600_solr) [n:127.0.0.1:38600_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/exitable/state.json] for collection [exitable] has occurred - updating... (live nodes size: [2])
[junit4] 2> 503615 INFO (zkCallback-598-thread-1-processing-n:127.0.0.1:33928_solr) [n:127.0.0.1:33928_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/exitable/state.json] for collection [exitable] has occurred - updating... (live nodes size: [2])
[junit4] 2> 503656 INFO (parallelCoreAdminExecutor-1864-thread-1-processing-n:127.0.0.1:38600_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310 CREATE) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 503664 INFO (parallelCoreAdminExecutor-1862-thread-1-processing-n:127.0.0.1:33928_solr 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081 CREATE) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 503766 INFO (zkCallback-597-thread-1-processing-n:127.0.0.1:38600_solr) [n:127.0.0.1:38600_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/exitable/state.json] for collection [exitable] has occurred - updating... (live nodes size: [2])
[junit4] 2> 503766 INFO (zkCallback-598-thread-1-processing-n:127.0.0.1:33928_solr) [n:127.0.0.1:33928_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/exitable/state.json] for collection [exitable] has occurred - updating... (live nodes size: [2])
[junit4] 2> 504319 INFO (qtp842538341-3860) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 504320 INFO (qtp842538341-3860) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 504484 INFO (qtp842538341-3865) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310
[junit4] 2> 504484 INFO (qtp842538341-3865) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=55147639-d76a-4e62-a144-aaf7eee31f4b125929794172310&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 504486 INFO (qtp758957976-3855) [n:127.0.0.1:33928_solr ] o.a.s.h.a.CoreAdminOperation Checking request status for : 55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081
[junit4] 2> 504486 INFO (qtp758957976-3855) [n:127.0.0.1:33928_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&requestid=55147639-d76a-4e62-a144-aaf7eee31f4b125929794359081&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 504488 INFO (OverseerThreadFactory-1861-thread-1-processing-n:127.0.0.1:38600_solr) [n:127.0.0.1:38600_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 505322 INFO (qtp842538341-3861) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :requeststatus with params requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=REQUESTSTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 505322 INFO (qtp842538341-3861) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=REQUESTSTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 505324 INFO (qtp842538341-3867) [n:127.0.0.1:38600_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deletestatus with params requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=DELETESTATUS&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 505324 INFO (qtp842538341-3867) [n:127.0.0.1:38600_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={requestid=55147639-d76a-4e62-a144-aaf7eee31f4b&action=DELETESTATUS&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 505335 INFO (TEST-CloudExitableDirectoryReaderTest.test-seed#[3AE0FEECAF9B0993]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 505347 INFO (qtp758957976-3851) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard2_replica1] webapp=/solr path=/update params={_stateVer_=exitable:3&wt=javabin&version=2}{add=[2 (1548582097600380928), 3 (1548582097607720960), 5 (1548582097607720961), 6 (1548582097607720962), 7 (1548582097607720963), 9 (1548582097607720964), 17 (1548582097607720965), 18 (1548582097607720966), 19 (1548582097607720967), 21 (1548582097607720968), ... (24 adds)]} 0 7
[junit4] 2> 505347 INFO (qtp842538341-3860) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard1_replica1] webapp=/solr path=/update params={_stateVer_=exitable:3&wt=javabin&version=2}{add=[1 (1548582097598283776), 4 (1548582097607720960), 8 (1548582097607720961), 10 (1548582097607720962), 11 (1548582097607720963), 12 (1548582097607720964), 13 (1548582097607720965), 14 (1548582097607720966), 15 (1548582097607720967), 16 (1548582097607720968), ... (33 adds)]} 0 9
[junit4] 2> 505353 INFO (qtp758957976-3852) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 505353 INFO (qtp842538341-3867) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 505354 INFO (qtp758957976-3852) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5d121fa9
[junit4] 2> 505354 INFO (qtp842538341-3867) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@73da1516
[junit4] 2> 505356 INFO (qtp758957976-3852) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@227befbc[exitable_shard2_replica1] main]
[junit4] 2> 505356 INFO (qtp842538341-3867) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@62b629c1[exitable_shard1_replica1] main]
[junit4] 2> 505356 INFO (qtp758957976-3852) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 505356 INFO (qtp842538341-3867) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 505356 INFO (searcherExecutor-1871-thread-1-processing-n:127.0.0.1:33928_solr x:exitable_shard2_replica1 s:shard2 c:exitable r:core_node2) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.SolrCore [exitable_shard2_replica1] Registered new searcher Searcher@227befbc[exitable_shard2_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C24)))}
[junit4] 2> 505356 INFO (searcherExecutor-1866-thread-1-processing-n:127.0.0.1:38600_solr x:exitable_shard1_replica1 s:shard1 c:exitable r:core_node1) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.SolrCore [exitable_shard1_replica1] Registered new searcher Searcher@62b629c1[exitable_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(6.3.0):C33)))}
[junit4] 2> 505357 INFO (qtp758957976-3852) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard2_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38600/solr/exitable_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 3
[junit4] 2> 505357 INFO (qtp842538341-3867) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:38600/solr/exitable_shard1_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 3
[junit4] 2> 505357 INFO (qtp842538341-3865) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [exitable_shard1_replica1] webapp=/solr path=/update params={_stateVer_=exitable:3&commit=true&wt=javabin&version=2}{commit=} 0 7
[junit4] 2> 505358 ERROR (TEST-CloudExitableDirectoryReaderTest.test-seed#[3AE0FEECAF9B0993]) [ ] o.a.s.c.s.i.CloudSolrClient Request to collection exitable failed due to (0) org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request, retry? 0
[junit4] 2> 505359 INFO (TEST-CloudExitableDirectoryReaderTest.test-seed#[3AE0FEECAF9B0993]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CloudExitableDirectoryReaderTest -Dtests.method=test -Dtests.seed=3AE0FEECAF9B0993 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ga -Dtests.timezone=JST -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 0.03s J0 | CloudExitableDirectoryReaderTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request
[junit4] > at __randomizedtesting.SeedInfo.seed([3AE0FEECAF9B0993:B2B4C1360167646B]:0)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:412)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1292)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1062)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1004)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:149)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942)
[junit4] > at org.apache.solr.cloud.CloudExitableDirectoryReaderTest.assertPartialResults(CloudExitableDirectoryReaderTest.java:106)
[junit4] > at org.apache.solr.cloud.CloudExitableDirectoryReaderTest.doTimeoutTests(CloudExitableDirectoryReaderTest.java:78)
[junit4] > at org.apache.solr.cloud.CloudExitableDirectoryReaderTest.test(CloudExitableDirectoryReaderTest.java:56)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 505361 INFO (jetty-launcher-587-thread-2) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@2c18d975{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 505361 INFO (jetty-launcher-587-thread-1) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@17bc607{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 505362 INFO (jetty-launcher-587-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=264445294
[junit4] 2> 505362 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=187377706
[junit4] 2> 505363 INFO (coreCloseExecutor-1877-thread-1) [n:127.0.0.1:33928_solr c:exitable s:shard2 r:core_node2 x:exitable_shard2_replica1] o.a.s.c.SolrCore [exitable_shard2_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@623027c7
[junit4] 2> 505363 INFO (coreCloseExecutor-1876-thread-1) [n:127.0.0.1:38600_solr c:exitable s:shard1 r:core_node1 x:exitable_shard1_replica1] o.a.s.c.SolrCore [exitable_shard1_replica1] CLOSING SolrCore org.apache.solr.core.SolrCore@466b883a
[junit4] 2> 505363 INFO (zkCallback-598-thread-1-processing-n:127.0.0.1:33928_solr) [n:127.0.0.1:33928_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/exitable/state.json] for collection [exitable] has occurred - updating... (live nodes size: [2])
[junit4] 2> 505364 INFO (zkCallback-597-thread-2-processing-n:127.0.0.1:38600_solr) [n:127.0.0.1:38600_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/exitable/state.json] for collection [exitable] has occurred - updating... (live nodes size: [2])
[junit4] 2> 505371 INFO (jetty-launcher-587-thread-1) [ ] o.a.s.c.Overseer Overseer (id=96786380887818245-127.0.0.1:38600_solr-n_0000000000) closing
[junit4] 2> 505371 INFO (OverseerStateUpdate-96786380887818245-127.0.0.1:38600_solr-n_0000000000) [n:127.0.0.1:38600_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:38600_solr
[junit4] 2> 505372 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x157dab35bea0006, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 505372 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x157dab35bea0005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 506872 WARN (zkCallback-597-thread-2-processing-n:127.0.0.1:38600_solr) [n:127.0.0.1:38600_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 506872 WARN (zkCallback-598-thread-1-processing-n:127.0.0.1:33928_solr) [n:127.0.0.1:33928_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 506872 INFO (jetty-launcher-587-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@75eb3806{/solr,null,UNAVAILABLE}
[junit4] 2> 506872 INFO (jetty-launcher-587-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5eda10dd{/solr,null,UNAVAILABLE}
[junit4] 2> 506873 INFO (SUITE-CloudExitableDirectoryReaderTest-seed#[3AE0FEECAF9B0993]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46202 46202
[junit4] 2> 506885 INFO (Thread-952) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:46202 46202
[junit4] 2> 506886 WARN (Thread-952) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 3 /solr/collections/exitable/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 506886 INFO (SUITE-CloudExitableDirectoryReaderTest-seed#[3AE0FEECAF9B0993]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-6.x-Linux/solr/build/solr-core/test/J0/temp/solr.cloud.CloudExitableDirectoryReaderTest_3AE0FEECAF9B0993-001
[junit4] 2> Oct 19, 2016 2:09:34 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {_version_=PostingsFormat(name=LuceneFixedGap), name=Lucene50(blocksize=128), id=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=1267, maxMBSortInHeap=5.716839380817828, sim=ClassicSimilarity, locale=ga, timezone=JST
[junit4] 2> NOTE: Linux 4.4.0-42-generic amd64/Oracle Corporation 1.8.0_102 (64-bit)/cpus=12,threads=1,free=164865296,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestBinaryResponseWriter, BlockJoinFacetSimpleTest, UUIDFieldTest, TestComponentsName, CollectionTooManyReplicasTest, AsyncCallRequestStatusResponseTest, TestConfigSetsAPIExclusivity, TestRawResponseWriter, TestHighlightDedupGrouping, TriLevelCompositeIdRoutingTest, HdfsBasicDistributedZkTest, TestExactSharedStatsCache, TestSearcherReuse, BasicDistributedZkTest, TestMissingGroups, PeerSyncTest, ScriptEngineTest, TestFieldCacheVsDocValues, CdcrVersionReplicationTest, TestPHPSerializedResponseWriter, TestDistribDocBasedVersion, CoreAdminRequestStatusTest, SolrCoreTest, TestJmxIntegration, DistributedVersionInfoTest, NotRequiredUniqueKeyTest, TestSolrQueryParserResource, TestRandomDVFaceting, TestConfigSetImmutable, TestReRankQParserPlugin, OutputWriterTest, CursorMarkTest, TestIntervalFaceting, SortSpecParsingTest, DocExpirationUpdateProcessorFactoryTest, TestSolrDeletionPolicy2, TestHighFrequencyDictionaryFactory, TestTolerantUpdateProcessorCloud, TestCSVLoader, AtomicUpdatesTest, TestHashQParserPlugin, TestCodecSupport, TestFieldResource, TestFunctionQuery, TestHdfsBackupRestoreCore, LeaderFailoverAfterPartitionTest, QueryEqualityTest, ExternalFileFieldSortTest, CursorPagingTest, TestCollationFieldDocValues, TestJoin, AnalysisAfterCoreReloadTest, TestManagedSynonymFilterFactory, OverseerTaskQueueTest, ChaosMonkeyNothingIsSafeTest, TestHdfsCloudBackupRestore, SecurityConfHandlerTest, CloudExitableDirectoryReaderTest]
[junit4] Completed [171/640 (1!)] on J0 in 5.05s, 1 test, 1 error <<< FAILURES!
[...truncated 61880 lines...]