Digging into the DocValuesNotIndexedTest failure. The UIMA has been failing even without the changes for SOLR-5260
On Sat, Nov 26, 2016 at 3:49 PM, Apache Jenkins Server <[email protected]> wrote: > Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/1502/ > > 5 tests failed. > FAILED: org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting > > Error Message: > Documents in wrong order for field: stringGSF expected:<[2]> but was:<[3]> > > Stack Trace: > org.junit.ComparisonFailure: Documents in wrong order for field: stringGSF > expected:<[2]> but was:<[3]> > at > __randomizedtesting.SeedInfo.seed([71022A804FE0F7AD:6F3A2288334B4D2D]:0) > at org.junit.Assert.assertEquals(Assert.java:125) > at > org.apache.solr.cloud.DocValuesNotIndexedTest.checkSortOrder(DocValuesNotIndexedTest.java:247) > at > org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting(DocValuesNotIndexedTest.java:227) > 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:1713) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at java.lang.Thread.run(Thread.java:745) > > > FAILED: > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testErrorHandling > > Error Message: > unknown UpdateRequestProcessorChain: <add><doc><field > name="id">2312312321312</field><field name="text">SpellCheckComponent got > improvement related to recent Lucene changes. Add support for specifying > Spelling SuggestWord Comparator to Lucene spell checkers for > SpellCheckComponent. Issue SOLR-2053 is already fixed, patch is attached if > you need it, but it is also committed to trunk and 3_x branch. Last Lucene > European Conference has been held in Prague.</field></doc></add> > > Stack Trace: > org.apache.solr.common.SolrException: unknown UpdateRequestProcessorChain: > <add><doc><field name="id">2312312321312</field><field > name="text">SpellCheckComponent got improvement related to recent Lucene > changes. > Add support for specifying Spelling SuggestWord Comparator to Lucene spell > checkers for SpellCheckComponent. Issue SOLR-2053 is already fixed, patch is > attached if you need it, but it is also committed to trunk and 3_x branch. > Last Lucene European Conference has been held in Prague.</field></doc></add> > at > __randomizedtesting.SeedInfo.seed([1213318AB190297F:27E74FB4E746A8AB]:0) > at > org.apache.solr.core.SolrCore.getUpdateProcessingChain(SolrCore.java:1214) > at > org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1222) > at > org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:52) > at org.apache.solr.SolrTestCaseJ4.addDoc(SolrTestCaseJ4.java:1031) > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testErrorHandling(UIMAUpdateRequestProcessorTest.java:137) > 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:1713) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at java.lang.Thread.run(Thread.java:745) > > > FAILED: > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testTwoUpdates > > Error Message: > unknown UpdateRequestProcessorChain: <add><doc><field > name="id">1</field><field name="text">The Apache Software Foundation is happy > to announce BarCampApache Sydney, Australia, the first ASF-backed event in > the Southern Hemisphere!</field></doc></add> > > Stack Trace: > org.apache.solr.common.SolrException: unknown UpdateRequestProcessorChain: > <add><doc><field name="id">1</field><field name="text">The Apache Software > Foundation is happy to announce BarCampApache Sydney, Australia, the first > ASF-backed event in the Southern Hemisphere!</field></doc></add> > at > __randomizedtesting.SeedInfo.seed([1213318AB190297F:1F5966D5A9BF4BD1]:0) > at > org.apache.solr.core.SolrCore.getUpdateProcessingChain(SolrCore.java:1214) > at > org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1222) > at > org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:52) > at org.apache.solr.SolrTestCaseJ4.addDoc(SolrTestCaseJ4.java:1031) > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testTwoUpdates(UIMAUpdateRequestProcessorTest.java:101) > 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:1713) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at java.lang.Thread.run(Thread.java:745) > > > FAILED: > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testMultiplierProcessing > > Error Message: > unknown UpdateRequestProcessorChain: <add><doc><field > name="id">2312312321312</field><field name="text">SpellCheckComponent got > improvement related to recent Lucene changes. Add support for specifying > Spelling SuggestWord Comparator to Lucene spell checkers for > SpellCheckComponent. Issue SOLR-2053 is already fixed, patch is attached if > you need it, but it is also committed to trunk and 3_x branch. Last Lucene > European Conference has been held in Prague.</field></doc></add> > > Stack Trace: > org.apache.solr.common.SolrException: unknown UpdateRequestProcessorChain: > <add><doc><field name="id">2312312321312</field><field > name="text">SpellCheckComponent got improvement related to recent Lucene > changes. > Add support for specifying Spelling SuggestWord Comparator to Lucene spell > checkers for SpellCheckComponent. Issue SOLR-2053 is already fixed, patch is > attached if you need it, but it is also committed to trunk and 3_x branch. > Last Lucene European Conference has been held in Prague.</field></doc></add> > at > __randomizedtesting.SeedInfo.seed([1213318AB190297F:2912FB27B6C4058B]:0) > at > org.apache.solr.core.SolrCore.getUpdateProcessingChain(SolrCore.java:1214) > at > org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1222) > at > org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:52) > at org.apache.solr.SolrTestCaseJ4.addDoc(SolrTestCaseJ4.java:1031) > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testProcessing(UIMAUpdateRequestProcessorTest.java:83) > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testMultiplierProcessing(UIMAUpdateRequestProcessorTest.java:177) > 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:1713) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at java.lang.Thread.run(Thread.java:745) > > > FAILED: > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testProcessing > > Error Message: > unknown UpdateRequestProcessorChain: <add><doc><field > name="id">2312312321312</field><field name="text">SpellCheckComponent got > improvement related to recent Lucene changes. Add support for specifying > Spelling SuggestWord Comparator to Lucene spell checkers for > SpellCheckComponent. Issue SOLR-2053 is already fixed, patch is attached if > you need it, but it is also committed to trunk and 3_x branch. Last Lucene > European Conference has been held in Prague.</field></doc></add> > > Stack Trace: > org.apache.solr.common.SolrException: unknown UpdateRequestProcessorChain: > <add><doc><field name="id">2312312321312</field><field > name="text">SpellCheckComponent got improvement related to recent Lucene > changes. > Add support for specifying Spelling SuggestWord Comparator to Lucene spell > checkers for SpellCheckComponent. Issue SOLR-2053 is already fixed, patch is > attached if you need it, but it is also committed to trunk and 3_x branch. > Last Lucene European Conference has been held in Prague.</field></doc></add> > at > __randomizedtesting.SeedInfo.seed([1213318AB190297F:19D35F8F663A8D5]:0) > at > org.apache.solr.core.SolrCore.getUpdateProcessingChain(SolrCore.java:1214) > at > org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1222) > at > org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:52) > at org.apache.solr.SolrTestCaseJ4.addDoc(SolrTestCaseJ4.java:1031) > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testProcessing(UIMAUpdateRequestProcessorTest.java:83) > 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:1713) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:811) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:462) > at > com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) > at > com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) > at > org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) > at > org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) > at > org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) > at > org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) > at > com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) > at > com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) > at java.lang.Thread.run(Thread.java:745) > > > > > Build Log: > [...truncated 12098 lines...] > [junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest > [junit4] 2> Creating dataDir: > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/init-core-data-001 > [junit4] 2> 1273035 INFO > (SUITE-DocValuesNotIndexedTest-seed#[71022A804FE0F7AD]-worker) [ ] > o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: > @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, > clientAuth=NaN) > [junit4] 2> 1273036 INFO > (SUITE-DocValuesNotIndexedTest-seed#[71022A804FE0F7AD]-worker) [ ] > o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001 > [junit4] 2> 1273036 INFO > (SUITE-DocValuesNotIndexedTest-seed#[71022A804FE0F7AD]-worker) [ ] > o.a.s.c.ZkTestServer STARTING ZK TEST SERVER > [junit4] 2> 1273036 INFO (Thread-5403) [ ] o.a.s.c.ZkTestServer > client port:0.0.0.0/0.0.0.0:0 > [junit4] 2> 1273036 INFO (Thread-5403) [ ] o.a.s.c.ZkTestServer > Starting server > [junit4] 2> 1273136 INFO > (SUITE-DocValuesNotIndexedTest-seed#[71022A804FE0F7AD]-worker) [ ] > o.a.s.c.ZkTestServer start zk server on port:54936 > [junit4] 2> 1273141 INFO (jetty-launcher-1972-thread-2) [ ] > o.e.j.s.Server jetty-9.3.14.v20161028 > [junit4] 2> 1273141 INFO (jetty-launcher-1972-thread-1) [ ] > o.e.j.s.Server jetty-9.3.14.v20161028 > [junit4] 2> 1273142 INFO (jetty-launcher-1972-thread-2) [ ] > o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@611aeeae{/solr,null,AVAILABLE} > [junit4] 2> 1273142 INFO (jetty-launcher-1972-thread-2) [ ] > o.e.j.s.AbstractConnector Started > ServerConnector@19cf17c3{HTTP/1.1,[http/1.1]}{127.0.0.1:42975} > [junit4] 2> 1273142 INFO (jetty-launcher-1972-thread-2) [ ] > o.e.j.s.Server Started @1276994ms > [junit4] 2> 1273142 INFO (jetty-launcher-1972-thread-2) [ ] > o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, > hostPort=42975} > [junit4] 2> 1273143 ERROR (jetty-launcher-1972-thread-2) [ ] > o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be > missing or incomplete. > [junit4] 2> 1273143 INFO (jetty-launcher-1972-thread-2) [ ] > o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version > 7.0.0 > [junit4] 2> 1273143 INFO (jetty-launcher-1972-thread-2) [ ] > o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port > null > [junit4] 2> 1273143 INFO (jetty-launcher-1972-thread-2) [ ] > o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null > [junit4] 2> 1273143 INFO (jetty-launcher-1972-thread-2) [ ] > o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: > 2016-11-26T23:14:19.386Z > [junit4] 2> 1273143 INFO (jetty-launcher-1972-thread-1) [ ] > o.e.j.s.h.ContextHandler Started > o.e.j.s.ServletContextHandler@477d3072{/solr,null,AVAILABLE} > [junit4] 2> 1273143 INFO (jetty-launcher-1972-thread-1) [ ] > o.e.j.s.AbstractConnector Started > ServerConnector@3c4ec033{HTTP/1.1,[http/1.1]}{127.0.0.1:43301} > [junit4] 2> 1273144 INFO (jetty-launcher-1972-thread-1) [ ] > o.e.j.s.Server Started @1276996ms > [junit4] 2> 1273144 INFO (jetty-launcher-1972-thread-1) [ ] > o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, > hostPort=43301} > [junit4] 2> 1273144 ERROR (jetty-launcher-1972-thread-1) [ ] > o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be > missing or incomplete. > [junit4] 2> 1273144 INFO (jetty-launcher-1972-thread-1) [ ] > o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version > 7.0.0 > [junit4] 2> 1273144 INFO (jetty-launcher-1972-thread-1) [ ] > o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port > null > [junit4] 2> 1273144 INFO (jetty-launcher-1972-thread-1) [ ] > o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null > [junit4] 2> 1273144 INFO (jetty-launcher-1972-thread-1) [ ] > o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: > 2016-11-26T23:14:19.387Z > [junit4] 2> 1273150 INFO (jetty-launcher-1972-thread-2) [ ] > o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... > [junit4] 2> 1273150 INFO (jetty-launcher-1972-thread-1) [ ] > o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... > [junit4] 2> 1273155 INFO (jetty-launcher-1972-thread-2) [ ] > o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54936/solr > [junit4] 2> 1273158 INFO (jetty-launcher-1972-thread-1) [ ] > o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:54936/solr > [junit4] 2> 1273174 INFO (jetty-launcher-1972-thread-1) > [n:127.0.0.1:43301_solr ] o.a.s.c.OverseerElectionContext I am going to be > the leader 127.0.0.1:43301_solr > [junit4] 2> 1273175 INFO (jetty-launcher-1972-thread-2) > [n:127.0.0.1:42975_solr ] o.a.s.c.ZkController Register node as live in > ZooKeeper:/live_nodes/127.0.0.1:42975_solr > [junit4] 2> 1273175 INFO (jetty-launcher-1972-thread-1) > [n:127.0.0.1:43301_solr ] o.a.s.c.Overseer Overseer > (id=97006522156974086-127.0.0.1:43301_solr-n_0000000000) starting > [junit4] 2> 1273176 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from > ZooKeeper... (0) -> (1) > [junit4] 2> 1273182 INFO > (zkCallback-1983-thread-1-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from > ZooKeeper... (0) -> (1) > [junit4] 2> 1273190 INFO (jetty-launcher-1972-thread-1) > [n:127.0.0.1:43301_solr ] o.a.s.c.ZkController Register node as live in > ZooKeeper:/live_nodes/127.0.0.1:43301_solr > [junit4] 2> 1273191 INFO > (zkCallback-1983-thread-1-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from > ZooKeeper... (1) -> (2) > [junit4] 2> 1273191 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from > ZooKeeper... (1) -> (2) > [junit4] 2> 1273240 INFO (jetty-launcher-1972-thread-1) > [n:127.0.0.1:43301_solr ] o.a.s.c.CorePropertiesLocator Found 0 core > definitions underneath > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node1/. > [junit4] 2> 1273264 INFO (jetty-launcher-1972-thread-2) > [n:127.0.0.1:42975_solr ] o.a.s.c.CorePropertiesLocator Found 0 core > definitions underneath > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node2/. > [junit4] 2> 1273268 INFO > (SUITE-DocValuesNotIndexedTest-seed#[71022A804FE0F7AD]-worker) [ ] > o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) > [junit4] 2> 1273269 INFO > (SUITE-DocValuesNotIndexedTest-seed#[71022A804FE0F7AD]-worker) [ ] > o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:54936/solr ready > [junit4] 2> 1273284 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with > params > replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&action=CREATE&numShards=4&wt=javabin&version=2 > and sendToOCPQueue=true > [junit4] 2> 1273285 INFO > (OverseerThreadFactory-5207-thread-1-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.c.CreateCollectionCmd Create collection > dv_coll > [junit4] 2> 1273391 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > ] o.a.s.h.a.CoreAdminOperation core create command > qt=/admin/cores&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica1&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2 > [junit4] 2> 1273392 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > ] o.a.s.h.a.CoreAdminOperation core create command > qt=/admin/cores&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica1&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2 > [junit4] 2> 1273392 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > ] o.a.s.h.a.CoreAdminOperation core create command > qt=/admin/cores&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica1&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2 > [junit4] 2> 1273397 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > ] o.a.s.h.a.CoreAdminOperation core create command > qt=/admin/cores&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2 > [junit4] 2> 1273500 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.c.c.ZkStateReader A cluster state change: > [WatchedEvent state:SyncConnected type:NodeDataChanged > path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - > updating... (live nodes size: [2]) > [junit4] 2> 1273500 INFO > (zkCallback-1983-thread-3-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.c.c.ZkStateReader A cluster state change: > [WatchedEvent state:SyncConnected type:NodeDataChanged > path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - > updating... (live nodes size: [2]) > [junit4] 2> 1274406 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 x:dv_coll_shard2_replica1] o.a.s.c.SolrConfig Using > Lucene MatchVersion: 7.0.0 > [junit4] 2> 1274408 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 x:dv_coll_shard3_replica1] o.a.s.c.SolrConfig Using > Lucene MatchVersion: 7.0.0 > [junit4] 2> 1274408 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 x:dv_coll_shard4_replica1] o.a.s.c.SolrConfig Using > Lucene MatchVersion: 7.0.0 > [junit4] 2> 1274413 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 x:dv_coll_shard1_replica1] o.a.s.c.SolrConfig Using > Lucene MatchVersion: 7.0.0 > [junit4] 2> 1274429 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 x:dv_coll_shard4_replica1] o.a.s.s.IndexSchema > [dv_coll_shard4_replica1] Schema name=minimal > [junit4] 2> 1274430 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 x:dv_coll_shard2_replica1] o.a.s.s.IndexSchema > [dv_coll_shard2_replica1] Schema name=minimal > [junit4] 2> 1274430 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 x:dv_coll_shard1_replica1] o.a.s.s.IndexSchema > [dv_coll_shard1_replica1] Schema name=minimal > [junit4] 2> 1274432 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 x:dv_coll_shard4_replica1] o.a.s.s.IndexSchema Loaded > schema minimal/1.1 with uniqueid field id > [junit4] 2> 1274432 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 x:dv_coll_shard2_replica1] o.a.s.s.IndexSchema Loaded > schema minimal/1.1 with uniqueid field id > [junit4] 2> 1274432 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 x:dv_coll_shard1_replica1] o.a.s.s.IndexSchema Loaded > schema minimal/1.1 with uniqueid field id > [junit4] 2> 1274433 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 x:dv_coll_shard4_replica1] o.a.s.c.CoreContainer Creating > SolrCore 'dv_coll_shard4_replica1' using configuration from collection dv_coll > [junit4] 2> 1274434 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 x:dv_coll_shard1_replica1] o.a.s.c.CoreContainer Creating > SolrCore 'dv_coll_shard1_replica1' using configuration from collection dv_coll > [junit4] 2> 1274434 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 x:dv_coll_shard2_replica1] o.a.s.c.CoreContainer Creating > SolrCore 'dv_coll_shard2_replica1' using configuration from collection dv_coll > [junit4] 2> 1274434 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] o.a.s.c.SolrCore > [[dv_coll_shard1_replica1] ] Opening new SolrCore at > [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node1/dv_coll_shard1_replica1], > > dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node1/./dv_coll_shard1_replica1/data/] > [junit4] 2> 1274434 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 x:dv_coll_shard3_replica1] o.a.s.s.IndexSchema > [dv_coll_shard3_replica1] Schema name=minimal > [junit4] 2> 1274435 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] o.a.s.c.SolrCore > [[dv_coll_shard4_replica1] ] Opening new SolrCore at > [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node2/dv_coll_shard4_replica1], > > dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node2/./dv_coll_shard4_replica1/data/] > [junit4] 2> 1274438 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] o.a.s.c.SolrCore > [[dv_coll_shard2_replica1] ] Opening new SolrCore at > [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node2/dv_coll_shard2_replica1], > > dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node2/./dv_coll_shard2_replica1/data/] > [junit4] 2> 1274438 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 x:dv_coll_shard3_replica1] o.a.s.s.IndexSchema Loaded > schema minimal/1.1 with uniqueid field id > [junit4] 2> 1274439 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 x:dv_coll_shard3_replica1] o.a.s.c.CoreContainer Creating > SolrCore 'dv_coll_shard3_replica1' using configuration from collection dv_coll > [junit4] 2> 1274442 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] o.a.s.c.SolrCore > [[dv_coll_shard3_replica1] ] Opening new SolrCore at > [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node1/dv_coll_shard3_replica1], > > dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node1/./dv_coll_shard3_replica1/data/] > [junit4] 2> 1274453 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.UpdateHandler Using UpdateLog implementation: > org.apache.solr.update.UpdateLog > [junit4] 2> 1274453 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.UpdateHandler Using UpdateLog implementation: > org.apache.solr.update.UpdateLog > [junit4] 2> 1274453 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] o.a.s.u.UpdateLog > Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH > numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 > [junit4] 2> 1274453 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] o.a.s.u.UpdateLog > Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH > numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 > [junit4] 2> 1274454 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.CommitTracker Hard AutoCommit: disabled > [junit4] 2> 1274454 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.CommitTracker Soft AutoCommit: disabled > [junit4] 2> 1274454 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.CommitTracker Hard AutoCommit: disabled > [junit4] 2> 1274454 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.CommitTracker Soft AutoCommit: disabled > [junit4] 2> 1274454 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.UpdateHandler Using UpdateLog implementation: > org.apache.solr.update.UpdateLog > [junit4] 2> 1274454 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] o.a.s.u.UpdateLog > Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH > numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 > [junit4] 2> 1274455 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.CommitTracker Hard AutoCommit: disabled > [junit4] 2> 1274455 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.CommitTracker Soft AutoCommit: disabled > [junit4] 2> 1274455 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.UpdateHandler Using UpdateLog implementation: > org.apache.solr.update.UpdateLog > [junit4] 2> 1274455 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] o.a.s.u.UpdateLog > Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH > numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 > [junit4] 2> 1274456 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.CommitTracker Hard AutoCommit: disabled > [junit4] 2> 1274456 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.CommitTracker Soft AutoCommit: disabled > [junit4] 2> 1274459 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@75a54f48[dv_coll_shard2_replica1] > main] > [junit4] 2> 1274459 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@49720998[dv_coll_shard4_replica1] > main] > [junit4] 2> 1274459 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@2915f2b1[dv_coll_shard3_replica1] > main] > [junit4] 2> 1274459 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@110b1111[dv_coll_shard1_replica1] > main] > [junit4] 2> 1274459 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: > /configs/conf1 > [junit4] 2> 1274460 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: > /configs/conf1 > [junit4] 2> 1274460 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using > ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1274460 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using > ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1274460 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema > at /configs/conf1/managed-schema > [junit4] 2> 1274460 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema > at /configs/conf1/managed-schema > [junit4] 2> 1274460 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: > /configs/conf1 > [junit4] 2> 1274460 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.h.ReplicationHandler Commits will be reserved for 10000 > [junit4] 2> 1274460 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: > /configs/conf1 > [junit4] 2> 1274460 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.h.ReplicationHandler Commits will be reserved for 10000 > [junit4] 2> 1274460 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] o.a.s.u.UpdateLog > Could not find max version in index or recent updates, using new clock > 1552104356003708928 > [junit4] 2> 1274460 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] o.a.s.u.UpdateLog > Could not find max version in index or recent updates, using new clock > 1552104356003708928 > [junit4] 2> 1274463 INFO > (searcherExecutor-5215-thread-1-processing-n:127.0.0.1:43301_solr > x:dv_coll_shard3_replica1 s:shard3 c:dv_coll r:core_node3) > [n:127.0.0.1:43301_solr c:dv_coll s:shard3 r:core_node3 > x:dv_coll_shard3_replica1] o.a.s.c.SolrCore [dv_coll_shard3_replica1] > Registered new searcher Searcher@2915f2b1[dv_coll_shard3_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1274463 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using > ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1274463 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema > at /configs/conf1/managed-schema > [junit4] 2> 1274463 INFO > (searcherExecutor-5212-thread-1-processing-n:127.0.0.1:42975_solr > x:dv_coll_shard4_replica1 s:shard4 c:dv_coll r:core_node1) > [n:127.0.0.1:42975_solr c:dv_coll s:shard4 r:core_node1 > x:dv_coll_shard4_replica1] o.a.s.c.SolrCore [dv_coll_shard4_replica1] > Registered new searcher Searcher@49720998[dv_coll_shard4_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1274463 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.h.ReplicationHandler Commits will be reserved for 10000 > [junit4] 2> 1274463 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] o.a.s.u.UpdateLog > Could not find max version in index or recent updates, using new clock > 1552104356006854656 > [junit4] 2> 1274464 INFO > (searcherExecutor-5214-thread-1-processing-n:127.0.0.1:42975_solr > x:dv_coll_shard2_replica1 s:shard2 c:dv_coll r:core_node2) > [n:127.0.0.1:42975_solr c:dv_coll s:shard2 r:core_node2 > x:dv_coll_shard2_replica1] o.a.s.c.SolrCore [dv_coll_shard2_replica1] > Registered new searcher Searcher@75a54f48[dv_coll_shard2_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1274463 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using > ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1274465 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema > at /configs/conf1/managed-schema > [junit4] 2> 1274466 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.h.ReplicationHandler Commits will be reserved for 10000 > [junit4] 2> 1274467 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] o.a.s.u.UpdateLog > Could not find max version in index or recent updates, using new clock > 1552104356011048960 > [junit4] 2> 1274469 INFO > (searcherExecutor-5213-thread-1-processing-n:127.0.0.1:43301_solr > x:dv_coll_shard1_replica1 s:shard1 c:dv_coll r:core_node4) > [n:127.0.0.1:43301_solr c:dv_coll s:shard1 r:core_node4 > x:dv_coll_shard1_replica1] o.a.s.c.SolrCore [dv_coll_shard1_replica1] > Registered new searcher Searcher@110b1111[dv_coll_shard1_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1274474 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 1274475 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 1274475 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.c.SyncStrategy Sync replicas to > http://127.0.0.1:42975/solr/dv_coll_shard4_replica1/ > [junit4] 2> 1274475 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.c.SyncStrategy Sync Success - now sync replicas to me > [junit4] 2> 1274475 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.c.SyncStrategy http://127.0.0.1:42975/solr/dv_coll_shard4_replica1/ has > no replicas > [junit4] 2> 1274476 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 1274476 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 1274476 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.c.SyncStrategy Sync replicas to > http://127.0.0.1:42975/solr/dv_coll_shard2_replica1/ > [junit4] 2> 1274476 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.c.SyncStrategy Sync Success - now sync replicas to me > [junit4] 2> 1274476 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.c.SyncStrategy http://127.0.0.1:42975/solr/dv_coll_shard2_replica1/ has > no replicas > [junit4] 2> 1274477 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 1274477 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 1274477 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.c.SyncStrategy Sync replicas to > http://127.0.0.1:43301/solr/dv_coll_shard3_replica1/ > [junit4] 2> 1274477 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.c.SyncStrategy Sync Success - now sync replicas to me > [junit4] 2> 1274477 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.c.SyncStrategy http://127.0.0.1:43301/solr/dv_coll_shard3_replica1/ has > no replicas > [junit4] 2> 1274478 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. > [junit4] 2> 1274478 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync > [junit4] 2> 1274479 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.c.SyncStrategy Sync replicas to > http://127.0.0.1:43301/solr/dv_coll_shard1_replica1/ > [junit4] 2> 1274479 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.c.SyncStrategy Sync Success - now sync replicas to me > [junit4] 2> 1274479 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.c.SyncStrategy http://127.0.0.1:43301/solr/dv_coll_shard1_replica1/ has > no replicas > [junit4] 2> 1274479 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.c.ShardLeaderElectionContext I am the new leader: > http://127.0.0.1:42975/solr/dv_coll_shard4_replica1/ shard4 > [junit4] 2> 1274480 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.c.ShardLeaderElectionContext I am the new leader: > http://127.0.0.1:42975/solr/dv_coll_shard2_replica1/ shard2 > [junit4] 2> 1274481 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.c.ShardLeaderElectionContext I am the new leader: > http://127.0.0.1:43301/solr/dv_coll_shard1_replica1/ shard1 > [junit4] 2> 1274482 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.c.ShardLeaderElectionContext I am the new leader: > http://127.0.0.1:43301/solr/dv_coll_shard3_replica1/ shard3 > [junit4] 2> 1274585 INFO > (zkCallback-1983-thread-1-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.c.c.ZkStateReader A cluster state change: > [WatchedEvent state:SyncConnected type:NodeDataChanged > path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - > updating... (live nodes size: [2]) > [junit4] 2> 1274585 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.c.c.ZkStateReader A cluster state change: > [WatchedEvent state:SyncConnected type:NodeDataChanged > path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - > updating... (live nodes size: [2]) > [junit4] 2> 1274630 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.c.ZkController I am the leader, no recovery necessary > [junit4] 2> 1274630 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.c.ZkController I am the leader, no recovery necessary > [junit4] 2> 1274632 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.c.ZkController I am the leader, no recovery necessary > [junit4] 2> 1274632 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores > params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica1&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2} > status=0 QTime=1240 > [junit4] 2> 1274632 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores > params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica1&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2} > status=0 QTime=1240 > [junit4] 2> 1274633 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.c.ZkController I am the leader, no recovery necessary > [junit4] 2> 1274635 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores > params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2} > status=0 QTime=1238 > [junit4] 2> 1274637 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores > params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica1&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2} > status=0 QTime=1245 > [junit4] 2> 1274641 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at > most 30 seconds. Check all shard replicas > [junit4] 2> 1274744 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.c.c.ZkStateReader A cluster state change: > [WatchedEvent state:SyncConnected type:NodeDataChanged > path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - > updating... (live nodes size: [2]) > [junit4] 2> 1274744 INFO > (zkCallback-1983-thread-3-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.c.c.ZkStateReader A cluster state change: > [WatchedEvent state:SyncConnected type:NodeDataChanged > path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - > updating... (live nodes size: [2]) > [junit4] 2> 1275287 INFO > (OverseerCollectionConfigSetProcessor-97006522156974086-127.0.0.1:43301_solr-n_0000000000) > [n:127.0.0.1:43301_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> 1275641 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections > params={replicationFactor=1&collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&action=CREATE&numShards=4&wt=javabin&version=2} > status=0 QTime=2357 > [junit4] 2> 1275646 INFO (qtp1211681484-14294) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.SchemaManager managed schema loaded . version : 0 > [junit4] 2> 1275647 INFO (qtp1211681484-14294) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.IndexSchema [dv_coll_shard1_replica1] Schema name=minimal > [junit4] 2> 1275649 INFO (qtp1211681484-14294) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id > [junit4] 2> 1275659 INFO > (zkCallback-1983-thread-3-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: > WatchedEvent state:SyncConnected type:NodeDataChanged > path:/configs/conf1/managed-schema, has occurred - updating schema from > ZooKeeper ... > [junit4] 2> 1275659 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: > WatchedEvent state:SyncConnected type:NodeDataChanged > path:/configs/conf1/managed-schema, has occurred - updating schema from > ZooKeeper ... > [junit4] 2> 1275659 INFO > (zkCallback-1983-thread-1-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: > WatchedEvent state:SyncConnected type:NodeDataChanged > path:/configs/conf1/managed-schema, has occurred - updating schema from > ZooKeeper ... > [junit4] 2> 1275659 INFO (qtp1211681484-14294) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.c.ZkController Persisted config data to node > /configs/conf1/managed-schema > [junit4] 2> 1275659 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema > version 1 from ZooKeeper > [junit4] 2> 1275659 INFO > (zkCallback-1981-thread-2-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.ZkIndexSchemaReader A schema change: > WatchedEvent state:SyncConnected type:NodeDataChanged > path:/configs/conf1/managed-schema, has occurred - updating schema from > ZooKeeper ... > [junit4] 2> 1275661 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.IndexSchema [dv_coll_shard2_replica1] > Schema name=minimal > [junit4] 2> 1275659 INFO > (zkCallback-1983-thread-3-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema > version 1 from ZooKeeper > [junit4] 2> 1275664 INFO > (zkCallback-1983-thread-3-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.IndexSchema [dv_coll_shard3_replica1] > Schema name=minimal > [junit4] 2> 1275672 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 > with uniqueid field id > [junit4] 2> 1275672 INFO > (zkCallback-1981-thread-1-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing > schema in 12 ms > [junit4] 2> 1275674 INFO > (zkCallback-1983-thread-3-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 > with uniqueid field id > [junit4] 2> 1275675 INFO > (zkCallback-1983-thread-3-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing > schema in 11 ms > [junit4] 2> 1275675 INFO > (zkCallback-1981-thread-2-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema > version 1 from ZooKeeper > [junit4] 2> 1275677 INFO > (zkCallback-1981-thread-2-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.IndexSchema [dv_coll_shard4_replica1] > Schema name=minimal > [junit4] 2> 1275692 INFO > (zkCallback-1981-thread-2-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 > with uniqueid field id > [junit4] 2> 1275692 INFO > (zkCallback-1981-thread-2-processing-n:127.0.0.1:42975_solr) > [n:127.0.0.1:42975_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing > schema in 17 ms > [junit4] 2> 1275693 INFO (Thread-5422) [n:127.0.0.1:43301_solr ] > o.a.s.c.SolrCore config update listener called for core > dv_coll_shard1_replica1 > [junit4] 2> 1275694 INFO (Thread-5422) [n:127.0.0.1:43301_solr ] > o.a.s.s.SchemaManager managed schema loaded . version : 1 > [junit4] 2> 1275696 INFO (Thread-5422) [n:127.0.0.1:43301_solr ] > o.a.s.s.IndexSchema [dv_coll_shard1_replica1] Schema name=minimal > [junit4] 2> 1275700 INFO (Thread-5422) [n:127.0.0.1:43301_solr ] > o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id > [junit4] 2> 1275701 INFO (Thread-5422) [n:127.0.0.1:43301_solr ] > o.a.s.c.SolrCore core reload dv_coll_shard1_replica1 > [junit4] 2> 1275701 INFO (Thread-5423) [n:127.0.0.1:42975_solr ] > o.a.s.c.SolrCore config update listener called for core > dv_coll_shard2_replica1 > [junit4] 2> 1275701 INFO (qtp1211681484-14294) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 3 replicas to apply > schema update version 1 for collection dv_coll > [junit4] 2> 1275702 INFO (Thread-5423) [n:127.0.0.1:42975_solr ] > o.a.s.c.SolrCore config update listener called for core > dv_coll_shard4_replica1 > [junit4] 2> 1275708 INFO (Thread-5422) [n:127.0.0.1:43301_solr ] > o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0 > [junit4] 2> 1275716 INFO (Thread-5422) [n:127.0.0.1:43301_solr ] > o.a.s.s.IndexSchema [dv_coll_shard1_replica1] Schema name=minimal > [junit4] 2> 1275721 INFO (Thread-5422) [n:127.0.0.1:43301_solr ] > o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id > [junit4] 2> 1275723 INFO (Thread-5422) [n:127.0.0.1:43301_solr ] > o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard1_replica1' using > configuration from collection dv_coll > [junit4] 2> 1275766 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] o.a.s.c.SolrCore > [[dv_coll_shard1_replica1] ] Opening new SolrCore at > [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node1/dv_coll_shard1_replica1], > > dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J2/temp/solr.cloud.DocValuesNotIndexedTest_71022A804FE0F7AD-001/tempDir-001/node1/./dv_coll_shard1_replica1/data/] > [junit4] 2> 1275806 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.CommitTracker Hard AutoCommit: disabled > [junit4] 2> 1275806 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.CommitTracker Soft AutoCommit: disabled > [junit4] 2> 1275830 INFO (qtp295632187-14288) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] o.a.s.c.S.Request > [dv_coll_shard2_replica1] webapp=/solr path=/schema/zkversion > params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=9 > [junit4] 2> 1275830 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@1d3e8f9[dv_coll_shard1_replica1] > main] > [junit4] 2> 1275830 INFO (qtp1211681484-14298) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] o.a.s.c.S.Request > [dv_coll_shard3_replica1] webapp=/solr path=/schema/zkversion > params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0 > [junit4] 2> 1275831 INFO (qtp295632187-14290) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] o.a.s.c.S.Request > [dv_coll_shard4_replica1] webapp=/solr path=/schema/zkversion > params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=9 > [junit4] 2> 1275831 INFO (qtp1211681484-14294) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.ManagedIndexSchema Took 129.0ms for 3 replicas to apply schema update > version 1 for collection dv_coll > [junit4] 2> 1275831 INFO (qtp1211681484-14294) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] o.a.s.c.S.Request > [dv_coll_shard1_replica1] webapp=/solr path=/schema > params={wt=javabin&version=2} status=0 QTime=185 > [junit4] 2> 1275831 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: > /configs/conf1 > [junit4] 2> 1275832 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using > ZooKeeperStorageIO:path=/configs/conf1 > [junit4] 2> 1275832 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema > at /configs/conf1/managed-schema > [junit4] 2> 1275832 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.h.ReplicationHandler Commits will be reserved for 10000 > [junit4] 2> 1275832 INFO > (searcherExecutor-5233-thread-1-processing-n:127.0.0.1:43301_solr > x:dv_coll_shard1_replica1 s:shard1 c:dv_coll r:core_node4) > [n:127.0.0.1:43301_solr c:dv_coll s:shard1 r:core_node4 > x:dv_coll_shard1_replica1] o.a.s.c.SolrCore [dv_coll_shard1_replica1] > Registered new searcher Searcher@1d3e8f9[dv_coll_shard1_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1275833 INFO > (zkCallback-1983-thread-1-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema > version 1 from ZooKeeper > [junit4] 2> 1275836 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] o.a.s.u.UpdateLog > Could not find max version in index or recent updates, using new clock > 1552104357446549504 > [junit4] 2> 1275839 INFO > (zkCallback-1983-thread-1-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.IndexSchema [dv_coll_shard1_replica1] > Schema name=minimal > [junit4] 2> 1275848 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used. > [junit4] 2> 1275850 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@7394a0a7[dv_coll_shard1_replica1] > main] > [junit4] 2> 1275854 INFO > (searcherExecutor-5233-thread-1-processing-n:127.0.0.1:43301_solr > x:dv_coll_shard1_replica1 s:shard1 c:dv_coll r:core_node4) > [n:127.0.0.1:43301_solr c:dv_coll s:shard1 r:core_node4 > x:dv_coll_shard1_replica1] o.a.s.c.SolrCore [dv_coll_shard1_replica1] > Registered new searcher Searcher@7394a0a7[dv_coll_shard1_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1275857 INFO > (TEST-DocValuesNotIndexedTest.testGroupingDVOnly-seed#[71022A804FE0F7AD]) [ > ] o.a.s.SolrTestCaseJ4 ###Starting testGroupingDVOnly > [junit4] 2> 1275870 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] o.a.s.c.SolrCore > [dv_coll_shard1_replica1] CLOSING SolrCore > org.apache.solr.core.SolrCore@59a37a22 > [junit4] 2> 1275870 INFO > (zkCallback-1983-thread-1-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 > with uniqueid field id > [junit4] 2> 1275871 INFO > (zkCallback-1983-thread-1-processing-n:127.0.0.1:43301_solr) > [n:127.0.0.1:43301_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing > schema in 37 ms > [junit4] 2> 1275871 INFO (Thread-5422) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] o.a.s.c.SolrCore > config update listener called for core dv_coll_shard3_replica1 > [junit4] 2> 1275885 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica1] webapp=/solr > path=/update > params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:42975/solr/dv_coll_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:* > (-1552104357483249664)} 0 14 > [junit4] 2> 1275886 INFO (qtp295632187-14293) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica1] webapp=/solr > path=/update > params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:42975/solr/dv_coll_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:* > (-1552104357484298240)} 0 14 > [junit4] 2> 1275886 INFO (qtp1211681484-14295) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica1] webapp=/solr > path=/update > params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:42975/solr/dv_coll_shard2_replica1/&wt=javabin&version=2}{deleteByQuery=*:* > (-1552104357490589696)} 0 8 > [junit4] 2> 1275887 INFO (qtp295632187-14288) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica1] webapp=/solr > path=/update params={wt=javabin&version=2}{deleteByQuery=*:* > (-1552104357481152512)} 0 28 > [junit4] 2> 1275888 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.DirectUpdateHandler2 start > commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 1275888 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. > [junit4] 2> 1275889 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.DirectUpdateHandler2 start > commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 1275889 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. > [junit4] 2> 1275901 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.DirectUpdateHandler2 end_commit_flush > [junit4] 2> 1275901 INFO (qtp1211681484-14297) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica1] webapp=/solr > path=/update > params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43301/solr/dv_coll_shard3_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} > 0 13 > [junit4] 2> 1275901 INFO (qtp295632187-14287) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.DirectUpdateHandler2 start > commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 1275901 INFO (qtp295632187-14287) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. > [junit4] 2> 1275901 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@65f3da2b[dv_coll_shard3_replica1] > main] > [junit4] 2> 1275904 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.DirectUpdateHandler2 end_commit_flush > [junit4] 2> 1275904 INFO > (searcherExecutor-5215-thread-1-processing-n:127.0.0.1:43301_solr > x:dv_coll_shard3_replica1 s:shard3 c:dv_coll r:core_node3) > [n:127.0.0.1:43301_solr c:dv_coll s:shard3 r:core_node3 > x:dv_coll_shard3_replica1] o.a.s.c.SolrCore [dv_coll_shard3_replica1] > Registered new searcher Searcher@65f3da2b[dv_coll_shard3_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1275904 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.DirectUpdateHandler2 start > commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 1275904 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica1] webapp=/solr > path=/update > params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43301/solr/dv_coll_shard3_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} > 0 15 > [junit4] 2> 1275904 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit. > [junit4] 2> 1275904 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@657a74d3[dv_coll_shard4_replica1] > main] > [junit4] 2> 1275905 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.DirectUpdateHandler2 end_commit_flush > [junit4] 2> 1275905 INFO > (searcherExecutor-5212-thread-1-processing-n:127.0.0.1:42975_solr > x:dv_coll_shard4_replica1 s:shard4 c:dv_coll r:core_node1) > [n:127.0.0.1:42975_solr c:dv_coll s:shard4 r:core_node1 > x:dv_coll_shard4_replica1] o.a.s.c.SolrCore [dv_coll_shard4_replica1] > Registered new searcher Searcher@657a74d3[dv_coll_shard4_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1275905 INFO (qtp295632187-14284) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica1] webapp=/solr > path=/update > params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43301/solr/dv_coll_shard3_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} > 0 0 > [junit4] 2> 1275905 INFO (qtp295632187-14287) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@1db65dc[dv_coll_shard2_replica1] > main] > [junit4] 2> 1275905 INFO (qtp295632187-14287) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.DirectUpdateHandler2 end_commit_flush > [junit4] 2> 1275905 INFO > (searcherExecutor-5214-thread-1-processing-n:127.0.0.1:42975_solr > x:dv_coll_shard2_replica1 s:shard2 c:dv_coll r:core_node2) > [n:127.0.0.1:42975_solr c:dv_coll s:shard2 r:core_node2 > x:dv_coll_shard2_replica1] o.a.s.c.SolrCore [dv_coll_shard2_replica1] > Registered new searcher Searcher@1db65dc[dv_coll_shard2_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader())} > [junit4] 2> 1275905 INFO (qtp295632187-14287) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica1] webapp=/solr > path=/update > params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43301/solr/dv_coll_shard3_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} > 0 4 > [junit4] 2> 1275905 INFO (qtp1211681484-14298) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica1] webapp=/solr > path=/update > params={_stateVer_=dv_coll:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} > 0 18 > [junit4] 2> 1275910 INFO (qtp1211681484-14294) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica1] webapp=/solr > path=/update params={_stateVer_=dv_coll:3&wt=javabin&version=2}{add=[2 > (1552104357523095552), 3 (1552104357524144128), 105 (1552104357524144129), 6 > (1552104357524144130), 7 (1552104357524144131), 9 (1552104357524144132), 115 > (1552104357524144133), 18 (1552104357524144134), 120 (1552104357524144135), > 26 (1552104357524144136), ... (15 adds)]} 0 1 > [junit4] 2> 1275912 INFO (qtp1211681484-14295) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica1] webapp=/solr > path=/update params={_stateVer_=dv_coll:3&wt=javabin&version=2}{add=[1 > (1552104357525192704), 8 (1552104357525192705), 10 (1552104357525192706), 110 > (1552104357525192707), 11 (1552104357526241280), 13 (1552104357526241281), 14 > (1552104357526241282), 24 (1552104357526241283), 27 (1552104357526241284), 28 > (1552104357526241285), ... (15 adds)]} 0 1 > [junit4] 2> 1275912 INFO (qtp295632187-14282) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica1] webapp=/solr > path=/update params={_stateVer_=dv_coll:3&wt=javabin&version=2}{add=[5 > (1552104357525192704), 17 (1552104357526241280), 19 (1552104357526241281), 21 > (1552104357526241282), 23 (1552104357526241283), 25 (1552104357526241284), 29 > (1552104357526241285), 33 (1552104357526241286), 37 (1552104357526241287), 45 > (1552104357526241288), ... (11 adds)]} 0 0 > [junit4] 2> 1275914 INFO (qtp295632187-14293) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica1] webapp=/solr > path=/update params={_stateVer_=dv_coll:3&wt=javabin&version=2}{add=[0 > (1552104357527289856), 100 (1552104357528338432), 4 (1552104357528338433), 12 > (1552104357528338434), 15 (1552104357528338435), 16 (1552104357528338436), 20 > (1552104357528338437), 22 (1552104357528338438), 125 (1552104357528338439), > 34 (1552104357528338440), ... (18 adds)]} 0 1 > [junit4] 2> 1275915 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.DirectUpdateHandler2 start > commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 1275915 INFO (qtp295632187-14291) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.DirectUpdateHandler2 start > commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 1275915 INFO (qtp1211681484-14286) [n:127.0.0.1:43301_solr > c:dv_coll s:shard1 r:core_node4 x:dv_coll_shard1_replica1] > o.a.s.u.SolrIndexWriter Calling setCommitData with > IW:org.apache.solr.update.SolrIndexWriter@23c8ca > [junit4] 2> 1275916 INFO (qtp295632187-14291) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.SolrIndexWriter Calling setCommitData with > IW:org.apache.solr.update.SolrIndexWriter@260c3034 > [junit4] 2> 1275916 INFO (qtp295632187-14288) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.DirectUpdateHandler2 start > commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 1275916 INFO (qtp295632187-14288) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.SolrIndexWriter Calling setCommitData with > IW:org.apache.solr.update.SolrIndexWriter@81eec86 > [junit4] 2> 1275916 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.DirectUpdateHandler2 start > commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} > [junit4] 2> 1275916 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.SolrIndexWriter Calling setCommitData with > IW:org.apache.solr.update.SolrIndexWriter@6c03587 > [junit4] 2> 1275926 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@338da681[dv_coll_shard3_replica1] > main] > [junit4] 2> 1275926 INFO (qtp295632187-14288) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@563cda64[dv_coll_shard2_replica1] > main] > [junit4] 2> 1275926 INFO > (searcherExecutor-5214-thread-1-processing-n:127.0.0.1:42975_solr > x:dv_coll_shard2_replica1 s:shard2 c:dv_coll r:core_node2) > [n:127.0.0.1:42975_solr c:dv_coll s:shard2 r:core_node2 > x:dv_coll_shard2_replica1] o.a.s.c.SolrCore [dv_coll_shard2_replica1] > Registered new searcher Searcher@563cda64[dv_coll_shard2_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C18)))} > [junit4] 2> 1275926 INFO > (searcherExecutor-5215-thread-1-processing-n:127.0.0.1:43301_solr > x:dv_coll_shard3_replica1 s:shard3 c:dv_coll r:core_node3) > [n:127.0.0.1:43301_solr c:dv_coll s:shard3 r:core_node3 > x:dv_coll_shard3_replica1] o.a.s.c.SolrCore [dv_coll_shard3_replica1] > Registered new searcher Searcher@338da681[dv_coll_shard3_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C15)))} > [junit4] 2> 1275926 INFO (qtp295632187-14288) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.DirectUpdateHandler2 end_commit_flush > [junit4] 2> 1275926 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.DirectUpdateHandler2 end_commit_flush > [junit4] 2> 1275926 INFO (qtp295632187-14288) [n:127.0.0.1:42975_solr > c:dv_coll s:shard2 r:core_node2 x:dv_coll_shard2_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica1] webapp=/solr > path=/update > params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43301/solr/dv_coll_shard3_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} > 0 10 > [junit4] 2> 1275926 INFO (qtp1211681484-14292) [n:127.0.0.1:43301_solr > c:dv_coll s:shard3 r:core_node3 x:dv_coll_shard3_replica1] > o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica1] webapp=/solr > path=/update > params={update.distrib=FROMLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:43301/solr/dv_coll_shard3_replica1/&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false}{commit=} > 0 9 > [junit4] 2> 1275927 INFO (qtp295632187-14291) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.s.SolrIndexSearcher Opening [Searcher@62713a9e[dv_coll_shard4_replica1] > main] > [junit4] 2> 1275927 INFO (qtp295632187-14291) [n:127.0.0.1:42975_solr > c:dv_coll s:shard4 r:core_node1 x:dv_coll_shard4_replica1] > o.a.s.u.DirectUpdateHandler2 end_commit_flush > [junit4] 2> 1275927 INFO > (searcherExecutor-5212-thread-1-processing-n:127.0.0.1:42975_solr > x:dv_coll_shard4_replica1 s:shard4 c:dv_coll r:core_node1) > [n:127.0.0.1:42975_solr c:dv_coll s:shard4 r:core_node1 > x:dv_coll_shard4_replica1] o.a.s.c.SolrCore [dv_coll_shard4_replica1] > Registered new searcher Searcher@62713a9e[dv_coll_shard4_replica1] > main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C11)))} > [junit4] 2> 1275927 INFO (qtp295 > > [...truncated too long message...] > > d, HdfsRecoveryZkTest, OutOfBoxZkACLAndCredentialsProvidersTest, > RestartWhileUpdatingTest, TestDocTermOrds, DateFieldTest, AssignTest, > TestRecovery, TestTrieFacet, TestSolrQueryResponse, PrimUtilsTest, > ForceLeaderTest, DirectUpdateHandlerTest, PrimitiveFieldTypeTest, > TestCloudSchemaless, TestQuerySenderNoQuery, > DocExpirationUpdateProcessorFactoryTest, RuleEngineTest, > TestSimpleQParserPlugin, TestFieldCacheSanityChecker, TestGroupingSearch, > TestLRUStatsCache, TestIndexingPerformance, BufferStoreTest, > TestJsonFacetRefinement, SolrIndexConfigTest, TestNoOpRegenerator, > UnloadDistributedZkTest, TestFieldCacheVsDocValues, > XmlUpdateRequestHandlerTest, EnumFieldTest, OverseerModifyCollectionTest, > DeleteReplicaTest, BinaryUpdateRequestHandlerTest, > TestGraphTermsQParserPlugin, TestCustomSort, TestWriterPerf, > TestManagedSchemaAPI, TestLMDirichletSimilarityFactory, TestReload, > TestRangeQuery, SolrCoreTest, TestFiltering, > DistributedQueryElevationComponentTest, TestFoldingMultitermQuery, > TestStressLucene, SuggesterWFSTTest, NoCacheHeaderTest, TestUpdate, > SortByFunctionTest, DebugComponentTest, DisMaxRequestHandlerTest, > StandardRequestHandlerTest, TestQueryTypes, TermsComponentTest, > MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, MBeansHandlerTest, > HighlighterConfigTest, UpdateParamsTest, ResponseLogComponentTest, > TestSolrDeletionPolicy2, NumericFieldsTest, MinimalSchemaTest, > OutputWriterTest, TestLuceneMatchVersion, > TestLMJelinekMercerSimilarityFactory, TestJmxMonitoredMap, PluginInfoTest, > SpellingQueryConverterTest, SystemInfoHandlerTest, > DistributedIntervalFacetingTest, BaseCdcrDistributedZkTest, > CdcrReplicationDistributedZkTest, CdcrReplicationHandlerTest, > CdcrRequestHandlerTest, CleanupOldIndexTest, CollectionsAPISolrJTest, > DocValuesNotIndexedTest] > [junit4] Completed [470/658 (1!)] on J2 in 5.92s, 4 tests, 1 failure <<< > FAILURES! > > [...truncated 11786 lines...] > [junit4] Suite: > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest > [junit4] 2> Creating dataDir: > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-uima/test/J0/temp/solr.uima.processor.UIMAUpdateRequestProcessorTest_1213318AB190297F-001/init-core-data-001 > [junit4] 2> log4j:WARN No appenders could be found for logger > (org.eclipse.jetty.util.log). > [junit4] 2> log4j:WARN Please initialize the log4j system properly. > [junit4] 2> log4j:WARN See > http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info. > [junit4] 2> 11월 26, 2016 4:39:42 오후 WhitespaceTokenizer initialize > [junit4] 2> 정보: "Whitespace tokenizer successfully initialized" > [junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=UIMAUpdateRequestProcessorTest -Dtests.method=testErrorHandling > -Dtests.seed=1213318AB190297F -Dtests.multiplier=2 -Dtests.slow=true > -Dtests.locale=ko-KR -Dtests.timezone=America/Boise -Dtests.asserts=true > -Dtests.file.encoding=UTF-8 > [junit4] ERROR 5.59s J0 | > UIMAUpdateRequestProcessorTest.testErrorHandling <<< > [junit4] > Throwable #1: org.apache.solr.common.SolrException: unknown > UpdateRequestProcessorChain: <add><doc><field > name="id">2312312321312</field><field name="text">SpellCheckComponent got > improvement related to recent Lucene changes. > [junit4] > Add support for specifying Spelling SuggestWord Comparator > to Lucene spell checkers for SpellCheckComponent. Issue SOLR-2053 is already > fixed, patch is attached if you need it, but it is also committed to trunk > and 3_x branch. Last Lucene European Conference has been held in > Prague.</field></doc></add> > [junit4] > at > __randomizedtesting.SeedInfo.seed([1213318AB190297F:27E74FB4E746A8AB]:0) > [junit4] > at > org.apache.solr.core.SolrCore.getUpdateProcessingChain(SolrCore.java:1214) > [junit4] > at > org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1222) > [junit4] > at > org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:52) > [junit4] > at > org.apache.solr.SolrTestCaseJ4.addDoc(SolrTestCaseJ4.java:1031) > [junit4] > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testErrorHandling(UIMAUpdateRequestProcessorTest.java:137) > [junit4] > at java.lang.Thread.run(Thread.java:745) > [junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=UIMAUpdateRequestProcessorTest -Dtests.method=testTwoUpdates > -Dtests.seed=1213318AB190297F -Dtests.multiplier=2 -Dtests.slow=true > -Dtests.locale=ko-KR -Dtests.timezone=America/Boise -Dtests.asserts=true > -Dtests.file.encoding=UTF-8 > [junit4] ERROR 0.01s J0 | UIMAUpdateRequestProcessorTest.testTwoUpdates > <<< > [junit4] > Throwable #1: org.apache.solr.common.SolrException: unknown > UpdateRequestProcessorChain: <add><doc><field name="id">1</field><field > name="text">The Apache Software Foundation is happy to announce BarCampApache > Sydney, Australia, the first ASF-backed event in the Southern > Hemisphere!</field></doc></add> > [junit4] > at > __randomizedtesting.SeedInfo.seed([1213318AB190297F:1F5966D5A9BF4BD1]:0) > [junit4] > at > org.apache.solr.core.SolrCore.getUpdateProcessingChain(SolrCore.java:1214) > [junit4] > at > org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1222) > [junit4] > at > org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:52) > [junit4] > at > org.apache.solr.SolrTestCaseJ4.addDoc(SolrTestCaseJ4.java:1031) > [junit4] > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testTwoUpdates(UIMAUpdateRequestProcessorTest.java:101) > [junit4] > at java.lang.Thread.run(Thread.java:745) > [junit4] 2> 11월 26, 2016 4:39:46 오후 WhitespaceTokenizer initialize > [junit4] 2> 정보: "Whitespace tokenizer successfully initialized" > [junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=UIMAUpdateRequestProcessorTest > -Dtests.method=testMultiplierProcessing -Dtests.seed=1213318AB190297F > -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=ko-KR > -Dtests.timezone=America/Boise -Dtests.asserts=true > -Dtests.file.encoding=UTF-8 > [junit4] ERROR 0.01s J0 | > UIMAUpdateRequestProcessorTest.testMultiplierProcessing <<< > [junit4] > Throwable #1: org.apache.solr.common.SolrException: unknown > UpdateRequestProcessorChain: <add><doc><field > name="id">2312312321312</field><field name="text">SpellCheckComponent got > improvement related to recent Lucene changes. > [junit4] > Add support for specifying Spelling SuggestWord Comparator > to Lucene spell checkers for SpellCheckComponent. Issue SOLR-2053 is already > fixed, patch is attached if you need it, but it is also committed to trunk > and 3_x branch. Last Lucene European Conference has been held in > Prague.</field></doc></add> > [junit4] > at > __randomizedtesting.SeedInfo.seed([1213318AB190297F:2912FB27B6C4058B]:0) > [junit4] > at > org.apache.solr.core.SolrCore.getUpdateProcessingChain(SolrCore.java:1214) > [junit4] > at > org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1222) > [junit4] > at > org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:52) > [junit4] > at > org.apache.solr.SolrTestCaseJ4.addDoc(SolrTestCaseJ4.java:1031) > [junit4] > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testProcessing(UIMAUpdateRequestProcessorTest.java:83) > [junit4] > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testMultiplierProcessing(UIMAUpdateRequestProcessorTest.java:177) > [junit4] > at java.lang.Thread.run(Thread.java:745) > [junit4] 2> NOTE: reproduce with: ant test > -Dtestcase=UIMAUpdateRequestProcessorTest -Dtests.method=testProcessing > -Dtests.seed=1213318AB190297F -Dtests.multiplier=2 -Dtests.slow=true > -Dtests.locale=ko-KR -Dtests.timezone=America/Boise -Dtests.asserts=true > -Dtests.file.encoding=UTF-8 > [junit4] ERROR 0.01s J0 | UIMAUpdateRequestProcessorTest.testProcessing > <<< > [junit4] > Throwable #1: org.apache.solr.common.SolrException: unknown > UpdateRequestProcessorChain: <add><doc><field > name="id">2312312321312</field><field name="text">SpellCheckComponent got > improvement related to recent Lucene changes. > [junit4] > Add support for specifying Spelling SuggestWord Comparator > to Lucene spell checkers for SpellCheckComponent. Issue SOLR-2053 is already > fixed, patch is attached if you need it, but it is also committed to trunk > and 3_x branch. Last Lucene European Conference has been held in > Prague.</field></doc></add> > [junit4] > at > __randomizedtesting.SeedInfo.seed([1213318AB190297F:19D35F8F663A8D5]:0) > [junit4] > at > org.apache.solr.core.SolrCore.getUpdateProcessingChain(SolrCore.java:1214) > [junit4] > at > org.apache.solr.core.SolrCore.getUpdateProcessorChain(SolrCore.java:1222) > [junit4] > at > org.apache.solr.handler.ContentStreamHandlerBase.handleRequestBody(ContentStreamHandlerBase.java:52) > [junit4] > at > org.apache.solr.SolrTestCaseJ4.addDoc(SolrTestCaseJ4.java:1031) > [junit4] > at > org.apache.solr.uima.processor.UIMAUpdateRequestProcessorTest.testProcessing(UIMAUpdateRequestProcessorTest.java:83) > [junit4] > at java.lang.Thread.run(Thread.java:745) > [junit4] 2> NOTE: leaving temporary files on disk at: > /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/contrib/solr-uima/test/J0/temp/solr.uima.processor.UIMAUpdateRequestProcessorTest_1213318AB190297F-001 > [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, > docValues:{}, maxPointsInLeafNode=1543, maxMBSortInHeap=5.709335914606576, > sim=RandomSimilarity(queryNorm=true): {}, locale=ko-KR, timezone=America/Boise > [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation > 1.8.0_102 (64-bit)/cpus=4,threads=1,free=129871600,total=363331584 > [junit4] 2> NOTE: All tests run in this JVM: > [UIMAUpdateRequestProcessorTest] > [junit4] Completed [2/2 (1!)] on J0 in 12.82s, 6 tests, 4 errors <<< > FAILURES! > > [...truncated 51198 lines...] > > > > > --------------------------------------------------------------------- > To unsubscribe, e-mail: [email protected] > For additional commands, e-mail: [email protected] --------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
