Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/20379/ Java: 64bit/jdk-9-ea+181 -XX:+UseCompressedOops -XX:+UseParallelGC --illegal-access=deny
3 tests failed. FAILED: org.apache.solr.cloud.ForceLeaderTest.testReplicasInLIRNoLeader Error Message: Doc with id=1 not found in http://127.0.0.1:43369/ub/h/forceleader_test_collection due to: Path not found: /id; rsp={doc=null} Stack Trace: java.lang.AssertionError: Doc with id=1 not found in http://127.0.0.1:43369/ub/h/forceleader_test_collection due to: Path not found: /id; rsp={doc=null} at __randomizedtesting.SeedInfo.seed([96DE1B3AD690F101:70492FFAEF120860]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.HttpPartitionTest.assertDocExists(HttpPartitionTest.java:603) at org.apache.solr.cloud.HttpPartitionTest.assertDocsExistInAllReplicas(HttpPartitionTest.java:556) at org.apache.solr.cloud.ForceLeaderTest.testReplicasInLIRNoLeader(ForceLeaderTest.java:142) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) 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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.metrics.reporters.SolrSlf4jReporterTest.testReporter Error Message: count1=6, count2=6 - no 'solr.node' logs in: {numFound=0,start=0,docs=[]} Stack Trace: java.lang.AssertionError: count1=6, count2=6 - no 'solr.node' logs in: {numFound=0,start=0,docs=[]} at __randomizedtesting.SeedInfo.seed([96DE1B3AD690F101:C93A360DBD9C6244]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.metrics.reporters.SolrSlf4jReporterTest.testReporter(SolrSlf4jReporterTest.java:90) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) FAILED: org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping Error Message: Tests must be run with INFO level logging otherwise LogUpdateProcessor isn't used and can't be tested. Stack Trace: java.lang.AssertionError: Tests must be run with INFO level logging otherwise LogUpdateProcessor isn't used and can't be tested. at __randomizedtesting.SeedInfo.seed([96DE1B3AD690F101:E73AE5ECA07BD6CD]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping(UpdateRequestProcessorFactoryTest.java:101) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:564) 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:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 11718 lines...] [junit4] Suite: org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest [junit4] 2> 868283 INFO (SUITE-UpdateRequestProcessorFactoryTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.UpdateRequestProcessorFactoryTest_96DE1B3AD690F101-001/init-core-data-001 [junit4] 2> 868283 WARN (SUITE-UpdateRequestProcessorFactoryTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=33 numCloses=33 [junit4] 2> 868283 INFO (SUITE-UpdateRequestProcessorFactoryTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 868284 INFO (SUITE-UpdateRequestProcessorFactoryTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 868284 INFO (SUITE-UpdateRequestProcessorFactoryTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 868540 WARN (coreLoadExecutor-3467-thread-1) [ ] o.a.s.c.RequestHandlers no default request handler is registered (either '/select' or 'standard') [junit4] 2> 868541 WARN (coreLoadExecutor-3467-thread-1) [ ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead. [junit4] 2> 868543 INFO (SUITE-UpdateRequestProcessorFactoryTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 868544 INFO (TEST-UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testUpdateDistribChainSkipping [junit4] 2> 868544 INFO (TEST-UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testUpdateDistribChainSkipping [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=UpdateRequestProcessorFactoryTest -Dtests.method=testUpdateDistribChainSkipping -Dtests.seed=96DE1B3AD690F101 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=xog-UG -Dtests.timezone=America/Guyana -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 0.00s J1 | UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping <<< [junit4] > Throwable #1: java.lang.AssertionError: Tests must be run with INFO level logging otherwise LogUpdateProcessor isn't used and can't be tested. [junit4] > at __randomizedtesting.SeedInfo.seed([96DE1B3AD690F101:E73AE5ECA07BD6CD]:0) [junit4] > at org.apache.solr.update.processor.UpdateRequestProcessorFactoryTest.testUpdateDistribChainSkipping(UpdateRequestProcessorFactoryTest.java:101) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 868546 INFO (TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRequestTimeUrp [junit4] 2> 868547 INFO (TEST-UpdateRequestProcessorFactoryTest.testRequestTimeUrp-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRequestTimeUrp [junit4] 2> 868548 INFO (TEST-UpdateRequestProcessorFactoryTest.testConfiguration-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testConfiguration [junit4] 2> 868548 INFO (TEST-UpdateRequestProcessorFactoryTest.testConfiguration-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConfiguration [junit4] 2> 868548 INFO (SUITE-UpdateRequestProcessorFactoryTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.update.processor.UpdateRequestProcessorFactoryTest_96DE1B3AD690F101-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=113, maxMBSortInHeap=6.147032901445707, sim=RandomSimilarity(queryNorm=true): {}, locale=xog-UG, timezone=America/Guyana [junit4] 2> NOTE: Linux 4.10.0-27-generic amd64/Oracle Corporation 9 (64-bit)/cpus=8,threads=1,free=217100904,total=516423680 [junit4] 2> NOTE: All tests run in this JVM: [FileUtilsTest, ExitableDirectoryReaderTest, CSVRequestHandlerTest, TestLegacyFieldReuse, JsonLoaderTest, AlternateDirectoryTest, DistributedFacetPivotWhiteBoxTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, AtomicUpdatesTest, DirectSolrSpellCheckerTest, CoreAdminHandlerTest, ClusterStateUpdateTest, LeaderInitiatedRecoveryOnCommitTest, HdfsLockFactoryTest, TestPullReplica, TestDistributedGrouping, TestFreeTextSuggestions, TestLeaderInitiatedRecoveryThread, TestRandomRequestDistribution, SolrCloudExampleTest, TestComplexPhraseLeadingWildcard, TestCoreContainer, BlockJoinFacetDistribTest, TestBM25SimilarityFactory, TestSubQueryTransformerDistrib, ConnectionReuseTest, TestSweetSpotSimilarityFactory, RemoteQueryErrorTest, TestCloudManagedSchema, HdfsChaosMonkeySafeLeaderTest, TestTrieFacet, DistributedIntervalFacetingTest, TestRTGBase, TestGraphMLResponseWriter, TestSolrConfigHandlerConcurrent, V2StandaloneTest, MetricsConfigTest, ZkSolrClientTest, HighlighterTest, DOMUtilTest, TestSimpleTrackingShardHandler, TestFieldResource, TestInfoStreamLogging, TestCursorMarkWithoutUniqueKey, CurrencyFieldTypeTest, MissingSegmentRecoveryTest, TestCloudDeleteByQuery, HLLSerializationTest, SolrCmdDistributorTest, SystemInfoHandlerTest, JSONWriterTest, IndexSchemaRuntimeFieldTest, TestChildDocTransformer, ZkFailoverTest, VersionInfoTest, ChaosMonkeySafeLeaderTest, TestSolrIndexConfig, HdfsBasicDistributedZk2Test, TestSegmentSorting, TestSQLHandler, TestSolrCloudWithHadoopAuthPlugin, OpenCloseCoreStressTest, TestCustomDocTransformer, TestConfigSetProperties, ScriptEngineTest, TestPayloadScoreQParserPlugin, BlockDirectoryTest, TestSchemaNameResource, TestPostingsSolrHighlighter, TestCustomStream, LoggingHandlerTest, HdfsRecoverLeaseTest, DocValuesTest, MetricUtilsTest, AddBlockUpdateTest, BasicFunctionalityTest, CollectionsAPIDistributedZkTest, DistributedMLTComponentTest, TestReloadDeadlock, TestRawResponseWriter, SOLR749Test, ReplicaListTransformerTest, TestFiltering, TestNoOpRegenerator, HdfsUnloadDistributedZkTest, SolrMetricReporterTest, ConnectionManagerTest, MoveReplicaTest, TestSolrCLIRunExample, PropertiesRequestHandlerTest, TestPerFieldSimilarity, TestRestManager, ResponseHeaderTest, TestUnifiedSolrHighlighter, UpdateRequestProcessorFactoryTest] [junit4] Completed [287/732 (1!)] on J1 in 0.31s, 3 tests, 1 failure <<< FAILURES! [...truncated 58 lines...] [junit4] Suite: org.apache.solr.metrics.reporters.SolrSlf4jReporterTest [junit4] 2> 941139 INFO (SUITE-SolrSlf4jReporterTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.metrics.reporters.SolrSlf4jReporterTest_96DE1B3AD690F101-001/init-core-data-001 [junit4] 2> 941139 WARN (SUITE-SolrSlf4jReporterTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=4 numCloses=4 [junit4] 2> 941139 INFO (SUITE-SolrSlf4jReporterTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 941139 INFO (SUITE-SolrSlf4jReporterTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 941140 INFO (TEST-SolrSlf4jReporterTest.testReporter-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReporter [junit4] 2> 947386 INFO (TEST-SolrSlf4jReporterTest.testReporter-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReporter [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SolrSlf4jReporterTest -Dtests.method=testReporter -Dtests.seed=96DE1B3AD690F101 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lag-TZ -Dtests.timezone=Universal -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 6.25s J1 | SolrSlf4jReporterTest.testReporter <<< [junit4] > Throwable #1: java.lang.AssertionError: count1=6, count2=6 - no 'solr.node' logs in: {numFound=0,start=0,docs=[]} [junit4] > at __randomizedtesting.SeedInfo.seed([96DE1B3AD690F101:C93A360DBD9C6244]:0) [junit4] > at org.apache.solr.metrics.reporters.SolrSlf4jReporterTest.testReporter(SolrSlf4jReporterTest.java:90) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 947386 INFO (SUITE-SolrSlf4jReporterTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.metrics.reporters.SolrSlf4jReporterTest_96DE1B3AD690F101-001 [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=true): {}, locale=lag-TZ, timezone=Universal [junit4] 2> NOTE: Linux 4.10.0-27-generic amd64/Oracle Corporation 9 (64-bit)/cpus=8,threads=1,free=133044544,total=516423680 [junit4] 2> NOTE: All tests run in this JVM: [FileUtilsTest, ExitableDirectoryReaderTest, CSVRequestHandlerTest, TestLegacyFieldReuse, JsonLoaderTest, AlternateDirectoryTest, DistributedFacetPivotWhiteBoxTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, AtomicUpdatesTest, DirectSolrSpellCheckerTest, CoreAdminHandlerTest, ClusterStateUpdateTest, LeaderInitiatedRecoveryOnCommitTest, HdfsLockFactoryTest, TestPullReplica, TestDistributedGrouping, TestFreeTextSuggestions, TestLeaderInitiatedRecoveryThread, TestRandomRequestDistribution, SolrCloudExampleTest, TestComplexPhraseLeadingWildcard, TestCoreContainer, BlockJoinFacetDistribTest, TestBM25SimilarityFactory, TestSubQueryTransformerDistrib, ConnectionReuseTest, TestSweetSpotSimilarityFactory, RemoteQueryErrorTest, TestCloudManagedSchema, HdfsChaosMonkeySafeLeaderTest, TestTrieFacet, DistributedIntervalFacetingTest, TestRTGBase, TestGraphMLResponseWriter, TestSolrConfigHandlerConcurrent, V2StandaloneTest, MetricsConfigTest, ZkSolrClientTest, HighlighterTest, DOMUtilTest, TestSimpleTrackingShardHandler, TestFieldResource, TestInfoStreamLogging, TestCursorMarkWithoutUniqueKey, CurrencyFieldTypeTest, MissingSegmentRecoveryTest, TestCloudDeleteByQuery, HLLSerializationTest, SolrCmdDistributorTest, SystemInfoHandlerTest, JSONWriterTest, IndexSchemaRuntimeFieldTest, TestChildDocTransformer, ZkFailoverTest, VersionInfoTest, ChaosMonkeySafeLeaderTest, TestSolrIndexConfig, HdfsBasicDistributedZk2Test, TestSegmentSorting, TestSQLHandler, TestSolrCloudWithHadoopAuthPlugin, OpenCloseCoreStressTest, TestCustomDocTransformer, TestConfigSetProperties, ScriptEngineTest, TestPayloadScoreQParserPlugin, BlockDirectoryTest, TestSchemaNameResource, TestPostingsSolrHighlighter, TestCustomStream, LoggingHandlerTest, HdfsRecoverLeaseTest, DocValuesTest, MetricUtilsTest, AddBlockUpdateTest, BasicFunctionalityTest, CollectionsAPIDistributedZkTest, DistributedMLTComponentTest, TestReloadDeadlock, TestRawResponseWriter, SOLR749Test, ReplicaListTransformerTest, TestFiltering, TestNoOpRegenerator, HdfsUnloadDistributedZkTest, SolrMetricReporterTest, ConnectionManagerTest, MoveReplicaTest, TestSolrCLIRunExample, PropertiesRequestHandlerTest, TestPerFieldSimilarity, TestRestManager, ResponseHeaderTest, TestUnifiedSolrHighlighter, UpdateRequestProcessorFactoryTest, NoCacheHeaderTest, ConfigureRecoveryStrategyTest, TestFieldSortValues, TestCloudSearcherWarming, TestAtomicUpdateErrorCases, SolrSlf4jReporterTest] [junit4] Completed [307/732 (2!)] on J1 in 6.26s, 1 test, 1 failure <<< FAILURES! [...truncated 705 lines...] [junit4] Suite: org.apache.solr.cloud.ForceLeaderTest [junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ForceLeaderTest_96DE1B3AD690F101-001/init-core-data-001 [junit4] 2> 1472700 WARN (SUITE-ForceLeaderTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=56 numCloses=56 [junit4] 2> 1472700 INFO (SUITE-ForceLeaderTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true [junit4] 2> 1472700 INFO (SUITE-ForceLeaderTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="https://issues.apache.org/jira/browse/SOLR-5776";) [junit4] 2> 1472700 INFO (SUITE-ForceLeaderTest-seed#[96DE1B3AD690F101]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> 1472703 ERROR (Thread-3078) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 1472893 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1474168 WARN (qtp1576559203-14789) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1475356 WARN (OverseerThreadFactory-5541-thread-2) [ ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores. [junit4] 2> 1475629 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1476777 WARN (qtp317187013-14831) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1477038 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1478522 WARN (qtp585313575-14870) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1478809 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1479995 WARN (qtp249519881-14902) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1480029 INFO (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReplicasInLIRNoLeader [junit4] 2> 1481380 WARN (qtp317187013-14837) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1481399 WARN (qtp1576559203-14782) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1481400 WARN (qtp249519881-14903) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1483489 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 4 connections to: http://127.0.0.1:40777/ub/h, target: http://127.0.0.1:44443/ub/h [junit4] 2> 1483489 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 2 connections to: http://127.0.0.1:43369/ub/h, target: http://127.0.0.1:41173/ub/h [junit4] 2> 1483490 ERROR (updateExecutor-3639-thread-2) [ ] o.a.s.u.ErrorReportingConcurrentUpdateSolrClient error [junit4] 2> java.net.SocketException: Broken pipe (Write failed) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite0(Native Method) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111) [junit4] 2> at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:155) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.streamWrite(SessionOutputBufferImpl.java:126) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flushBuffer(SessionOutputBufferImpl.java:138) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flush(SessionOutputBufferImpl.java:146) [junit4] 2> at org.apache.http.impl.io.ChunkedOutputStream.close(ChunkedOutputStream.java:205) [junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.sendRequestEntity(DefaultBHttpClientConnection.java:159) [junit4] 2> at org.apache.http.impl.conn.CPoolProxy.sendRequestEntity(CPoolProxy.java:162) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:237) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:122) [junit4] 2> at org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:118) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.sendUpdateStream(ConcurrentUpdateSolrClient.java:347) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.run(ConcurrentUpdateSolrClient.java:182) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1483491 ERROR (updateExecutor-3639-thread-1) [ ] o.a.s.u.ErrorReportingConcurrentUpdateSolrClient error [junit4] 2> java.net.SocketException: Broken pipe (Write failed) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite0(Native Method) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111) [junit4] 2> at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:155) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.streamWrite(SessionOutputBufferImpl.java:126) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flushBuffer(SessionOutputBufferImpl.java:138) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flush(SessionOutputBufferImpl.java:146) [junit4] 2> at org.apache.http.impl.io.ChunkedOutputStream.close(ChunkedOutputStream.java:205) [junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.sendRequestEntity(DefaultBHttpClientConnection.java:159) [junit4] 2> at org.apache.http.impl.conn.CPoolProxy.sendRequestEntity(CPoolProxy.java:162) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:237) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:122) [junit4] 2> at org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:118) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.sendUpdateStream(ConcurrentUpdateSolrClient.java:347) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.run(ConcurrentUpdateSolrClient.java:182) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1483491 WARN (qtp317187013-14832) [ ] o.a.s.u.p.DistributedUpdateProcessor Error sending update to http://127.0.0.1:40777/ub/h [junit4] 2> java.net.SocketException: Broken pipe (Write failed) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite0(Native Method) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111) [junit4] 2> at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:155) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.streamWrite(SessionOutputBufferImpl.java:126) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flushBuffer(SessionOutputBufferImpl.java:138) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flush(SessionOutputBufferImpl.java:146) [junit4] 2> at org.apache.http.impl.io.ChunkedOutputStream.close(ChunkedOutputStream.java:205) [junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.sendRequestEntity(DefaultBHttpClientConnection.java:159) [junit4] 2> at org.apache.http.impl.conn.CPoolProxy.sendRequestEntity(CPoolProxy.java:162) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:237) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:122) [junit4] 2> at org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:118) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.sendUpdateStream(ConcurrentUpdateSolrClient.java:347) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.run(ConcurrentUpdateSolrClient.java:182) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1483491 ERROR (qtp317187013-14832) [ ] o.a.s.u.p.DistributedUpdateProcessor Setting up to try to start recovery on replica http://127.0.0.1:40777/ub/h/forceleader_test_collection_shard1_replica_t2/ [junit4] 2> java.net.SocketException: Broken pipe (Write failed) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite0(Native Method) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111) [junit4] 2> at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:155) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.streamWrite(SessionOutputBufferImpl.java:126) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flushBuffer(SessionOutputBufferImpl.java:138) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flush(SessionOutputBufferImpl.java:146) [junit4] 2> at org.apache.http.impl.io.ChunkedOutputStream.close(ChunkedOutputStream.java:205) [junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.sendRequestEntity(DefaultBHttpClientConnection.java:159) [junit4] 2> at org.apache.http.impl.conn.CPoolProxy.sendRequestEntity(CPoolProxy.java:162) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:237) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:122) [junit4] 2> at org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:118) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.sendUpdateStream(ConcurrentUpdateSolrClient.java:347) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.run(ConcurrentUpdateSolrClient.java:182) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1483492 WARN (qtp317187013-14832) [ ] o.a.s.u.p.DistributedUpdateProcessor Error sending update to http://127.0.0.1:43369/ub/h [junit4] 2> java.net.SocketException: Broken pipe (Write failed) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite0(Native Method) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111) [junit4] 2> at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:155) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.streamWrite(SessionOutputBufferImpl.java:126) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flushBuffer(SessionOutputBufferImpl.java:138) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flush(SessionOutputBufferImpl.java:146) [junit4] 2> at org.apache.http.impl.io.ChunkedOutputStream.close(ChunkedOutputStream.java:205) [junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.sendRequestEntity(DefaultBHttpClientConnection.java:159) [junit4] 2> at org.apache.http.impl.conn.CPoolProxy.sendRequestEntity(CPoolProxy.java:162) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:237) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:122) [junit4] 2> at org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:118) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.sendUpdateStream(ConcurrentUpdateSolrClient.java:347) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.run(ConcurrentUpdateSolrClient.java:182) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1483492 ERROR (qtp317187013-14832) [ ] o.a.s.u.p.DistributedUpdateProcessor Setting up to try to start recovery on replica http://127.0.0.1:43369/ub/h/forceleader_test_collection_shard1_replica_t3/ [junit4] 2> java.net.SocketException: Broken pipe (Write failed) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite0(Native Method) [junit4] 2> at java.base/java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111) [junit4] 2> at java.base/java.net.SocketOutputStream.write(SocketOutputStream.java:155) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.streamWrite(SessionOutputBufferImpl.java:126) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flushBuffer(SessionOutputBufferImpl.java:138) [junit4] 2> at org.apache.http.impl.io.SessionOutputBufferImpl.flush(SessionOutputBufferImpl.java:146) [junit4] 2> at org.apache.http.impl.io.ChunkedOutputStream.close(ChunkedOutputStream.java:205) [junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.sendRequestEntity(DefaultBHttpClientConnection.java:159) [junit4] 2> at org.apache.http.impl.conn.CPoolProxy.sendRequestEntity(CPoolProxy.java:162) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doSendRequest(HttpRequestExecutor.java:237) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:122) [junit4] 2> at org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:118) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:271) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.sendUpdateStream(ConcurrentUpdateSolrClient.java:347) [junit4] 2> at org.apache.solr.client.solrj.impl.ConcurrentUpdateSolrClient$Runner.run(ConcurrentUpdateSolrClient.java:182) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1483492 WARN (updateExecutor-3639-thread-1) [ ] o.a.s.c.LeaderInitiatedRecoveryThread Leader is publishing core=forceleader_test_collection_shard1_replica_t2 coreNodeName =core_node6 state=down on behalf of un-reachable replica http://127.0.0.1:40777/ub/h/forceleader_test_collection_shard1_replica_t2/ [junit4] 2> 1483492 WARN (updateExecutor-3639-thread-2) [ ] o.a.s.c.LeaderInitiatedRecoveryThread Leader is publishing core=forceleader_test_collection_shard1_replica_t3 coreNodeName =core_node5 state=down on behalf of un-reachable replica http://127.0.0.1:43369/ub/h/forceleader_test_collection_shard1_replica_t3/ [junit4] 2> 1483493 ERROR (updateExecutor-3639-thread-2) [ ] o.a.s.c.LeaderInitiatedRecoveryThread http://127.0.0.1:43369/ub/h: Could not tell a replica to recover:org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:43369/ub/h [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:634) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.cloud.LeaderInitiatedRecoveryThread.sendRecoveryCommandWithRetry(LeaderInitiatedRecoveryThread.java:209) [junit4] 2> at org.apache.solr.cloud.LeaderInitiatedRecoveryThread.run(LeaderInitiatedRecoveryThread.java:95) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> Caused by: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:43369 [/127.0.0.1] failed: Connection refused (Connection refused) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:151) [junit4] 2> at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:526) [junit4] 2> ... 10 more [junit4] 2> Caused by: java.net.ConnectException: Connection refused (Connection refused) [junit4] 2> at java.base/java.net.PlainSocketImpl.socketConnect(Native Method) [junit4] 2> at java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:400) [junit4] 2> at java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:243) [junit4] 2> at java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:225) [junit4] 2> at java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:402) [junit4] 2> at java.base/java.net.Socket.connect(Socket.java:591) [junit4] 2> at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134) [junit4] 2> ... 20 more [junit4] 2> [junit4] 2> 1483493 ERROR (updateExecutor-3639-thread-1) [ ] o.a.s.c.LeaderInitiatedRecoveryThread http://127.0.0.1:40777/ub/h: Could not tell a replica to recover:org.apache.solr.client.solrj.SolrServerException: Server refused connection at: http://127.0.0.1:40777/ub/h [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:634) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:253) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:242) [junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] 2> at org.apache.solr.cloud.LeaderInitiatedRecoveryThread.sendRecoveryCommandWithRetry(LeaderInitiatedRecoveryThread.java:209) [junit4] 2> at org.apache.solr.cloud.LeaderInitiatedRecoveryThread.run(LeaderInitiatedRecoveryThread.java:95) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> Caused by: org.apache.http.conn.HttpHostConnectException: Connect to 127.0.0.1:40777 [/127.0.0.1] failed: Connection refused (Connection refused) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:151) [junit4] 2> at org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:353) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:380) [junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:236) [junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:184) [junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:88) [junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110) [junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:184) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:526) [junit4] 2> ... 10 more [junit4] 2> Caused by: java.net.ConnectException: Connection refused (Connection refused) [junit4] 2> at java.base/java.net.PlainSocketImpl.socketConnect(Native Method) [junit4] 2> at java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:400) [junit4] 2> at java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:243) [junit4] 2> at java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:225) [junit4] 2> at java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:402) [junit4] 2> at java.base/java.net.Socket.connect(Socket.java:591) [junit4] 2> at org.apache.http.conn.socket.PlainConnectionSocketFactory.connectSocket(PlainConnectionSocketFactory.java:74) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:134) [junit4] 2> ... 20 more [junit4] 2> [junit4] 2> 1485492 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 6 connections to: http://127.0.0.1:43465/ub/h, target: http://127.0.0.1:33449/ub/h [junit4] 2> 1488494 WARN (updateExecutor-3639-thread-2) [ ] o.a.s.c.LeaderInitiatedRecoveryThread Stop trying to send recovery command to downed replica core=forceleader_test_collection_shard1_replica_t3 coreNodeName=core_node5 on 127.0.0.1:43369_ub%2Fh because my core container is closed. [junit4] 2> 1488494 WARN (updateExecutor-3639-thread-1) [ ] o.a.s.c.LeaderInitiatedRecoveryThread Stop trying to send recovery command to downed replica core=forceleader_test_collection_shard1_replica_t2 coreNodeName=core_node6 on 127.0.0.1:40777_ub%2Fh because my core container is closed. [junit4] 2> 1488494 WARN (zkCallback-3642-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1489498 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.s.i.CloudSolrClient Request to collection forceleader_test_collection failed due to (510) org.apache.solr.common.SolrException: Could not find a healthy node to handle the request., retry? 0 [junit4] 2> 1489498 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.AbstractFullDistribZkTestBase ERROR: org.apache.solr.common.SolrException: Could not find a healthy node to handle the request. ... Sleeping for 1 seconds before re-try ... [junit4] 2> 1490498 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.s.i.CloudSolrClient Request to collection forceleader_test_collection failed due to (510) org.apache.solr.common.SolrException: Could not find a healthy node to handle the request., retry? 0 [junit4] 2> 1490498 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.AbstractFullDistribZkTestBase ERROR: org.apache.solr.common.SolrException: Could not find a healthy node to handle the request. ... Sleeping for 1 seconds before re-try ... [junit4] 2> 1491301 WARN (zkCallback-3654-thread-3) [ ] o.a.s.c.ShardLeaderElectionContext The previous leader marked me forceleader_test_collection_shard1_replica_t3 as down and I haven't recovered yet, so I shouldn't be the leader. [junit4] 2> 1491301 ERROR (zkCallback-3654-thread-3) [ ] o.a.s.c.ShardLeaderElectionContext There was a problem trying to register as the leader:org.apache.solr.common.SolrException: Leader Initiated Recovery prevented leadership [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.checkLIR(ElectionContext.java:549) [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:431) [junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170) [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135) [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56) [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269) [junit4] 2> at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 1491498 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.s.i.CloudSolrClient Request to collection forceleader_test_collection failed due to (510) org.apache.solr.common.SolrException: Could not find a healthy node to handle the request., retry? 0 [junit4] 2> 1491498 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.AbstractFullDistribZkTestBase ERROR: org.apache.solr.common.SolrException: Could not find a healthy node to handle the request. ... Sleeping for 1 seconds before re-try ... [junit4] 2> 1492498 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.s.i.CloudSolrClient Request to collection forceleader_test_collection failed due to (510) org.apache.solr.common.SolrException: Could not find a healthy node to handle the request., retry? 0 [junit4] 2> 1492498 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.AbstractFullDistribZkTestBase ERROR: org.apache.solr.common.SolrException: Could not find a healthy node to handle the request. ... Sleeping for 1 seconds before re-try ... [junit4] 2> 1493499 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.s.i.CloudSolrClient Request to collection forceleader_test_collection failed due to (510) org.apache.solr.common.SolrException: Could not find a healthy node to handle the request., retry? 0 [junit4] 2> 1493499 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.AbstractFullDistribZkTestBase ERROR: org.apache.solr.common.SolrException: Could not find a healthy node to handle the request. ... Sleeping for 1 seconds before re-try ... [junit4] 2> 1493613 ERROR (indexFetcher-5602-thread-1) [ ] o.a.s.h.ReplicationHandler Index fetch failed :org.apache.solr.common.SolrException: No registered leader was found after waiting for 4000ms , collection: forceleader_test_collection slice: shard1 [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:758) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:744) [junit4] 2> at org.apache.solr.handler.IndexFetcher.getLeaderReplica(IndexFetcher.java:667) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:367) [junit4] 2> at org.apache.solr.handler.IndexFetcher.fetchLatestIndex(IndexFetcher.java:332) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:419) [junit4] 2> at org.apache.solr.handler.ReplicationHandler.lambda$setupPolling$12(ReplicationHandler.java:1183) [junit4] 2> at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [junit4] 2> at java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [junit4] 2> at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:300) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 1494105 WARN (zkCallback-3630-thread-2) [ ] o.a.s.c.ShardLeaderElectionContext The previous leader marked me forceleader_test_collection_shard1_replica_t2 as down and I haven't recovered yet, so I shouldn't be the leader. [junit4] 2> 1494105 ERROR (zkCallback-3630-thread-2) [ ] o.a.s.c.ShardLeaderElectionContext There was a problem trying to register as the leader:org.apache.solr.common.SolrException: Leader Initiated Recovery prevented leadership [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.checkLIR(ElectionContext.java:549) [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:431) [junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170) [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135) [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56) [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269) [junit4] 2> at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 2> 1494106 WARN (zkCallback-3654-thread-3) [ ] o.a.s.c.RecoveryStrategy Stopping recovery for core=[forceleader_test_collection_shard1_replica_t3] coreNodeName=[core_node5] [junit4] 2> 1494499 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.s.i.CloudSolrClient Request to collection forceleader_test_collection failed due to (510) org.apache.solr.common.SolrException: Could not find a healthy node to handle the request., retry? 0 [junit4] 2> 1494499 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.AbstractFullDistribZkTestBase No more retries available! Add batch failed due to: org.apache.solr.common.SolrException: Could not find a healthy node to handle the request. [junit4] 2> 1495308 ERROR (recoveryExecutor-3652-thread-1) [ ] o.a.s.c.RecoveryStrategy Error while trying to recover. core=forceleader_test_collection_shard1_replica_t3:org.apache.solr.common.SolrException: No registered leader was found after waiting for 4000ms , collection: forceleader_test_collection slice: shard1 [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:758) [junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:744) [junit4] 2> at org.apache.solr.cloud.RecoveryStrategy.doSyncOrReplicateRecovery(RecoveryStrategy.java:517) [junit4] 2> at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:302) [junit4] 2> at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:286) [junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176) [junit4] 2> at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> [junit4] 1> - [junit4] 1> replica:core_node4 rstate:down live:false [junit4] 1> replica:core_node5 rstate:active live:true [junit4] 1> replica:core_node6 rstate:active live:true [junit4] 1> no one is recoverying [junit4] 2> 1502081 WARN (qtp249519881-14904) [ ] o.a.s.c.RecoveryStrategy Stopping recovery for core=[forceleader_test_collection_shard1_replica_t3] coreNodeName=[core_node5] [junit4] 2> 1502082 WARN (qtp249519881-14904) [ ] o.a.s.c.RecoveryStrategy Stopping recovery for core=[forceleader_test_collection_shard1_replica_t3] coreNodeName=[core_node5] [junit4] 2> 1502084 WARN (zkCallback-3630-thread-2) [ ] o.a.s.c.LeaderElector Our node is no longer in line to be leader [junit4] 2> 1502787 INFO (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReplicasInLIRNoLeader [junit4] 2> 1504298 WARN (zkCallback-3630-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1504309 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception [junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x15e221d7f3a000c, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1505809 WARN (zkCallback-3648-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1507321 WARN (zkCallback-3654-thread-3) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1507322 ERROR (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 1507900 WARN (Thread-3078) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 4 /solr/security.json [junit4] 2> 4 /solr/configs/conf1 [junit4] 2> 3 /solr/collections/forceleader_test_collection/state.json [junit4] 2> 3 /solr/collections/collection1/state.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 6 /solr/clusterprops.json [junit4] 2> 2 /solr/overseer_elect/election/98553751932633092-127.0.0.1:40777_ub%2Fh-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 6 /solr/live_nodes [junit4] 2> 6 /solr/collections [junit4] 2> 3 /solr/overseer/queue [junit4] 2> 3 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> 1507900 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 14 connections to: http://127.0.0.1:43369/ub/h, target: http://127.0.0.1:41173/ub/h [junit4] 2> 1507900 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 3 connections to: http://127.0.0.1:38419/ub/h, target: http://127.0.0.1:43837/ub/h [junit4] 2> 1507900 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 4 connections to: http://127.0.0.1:40777/ub/h, target: http://127.0.0.1:44443/ub/h [junit4] 2> 1507900 WARN (TEST-ForceLeaderTest.testReplicasInLIRNoLeader-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 0 connections to: http://127.0.0.1:43465/ub/h, target: http://127.0.0.1:33449/ub/h [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ForceLeaderTest -Dtests.method=testReplicasInLIRNoLeader -Dtests.seed=96DE1B3AD690F101 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-Latn-ME -Dtests.timezone=Canada/Saskatchewan -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] FAILURE 35.2s J1 | ForceLeaderTest.testReplicasInLIRNoLeader <<< [junit4] > Throwable #1: java.lang.AssertionError: Doc with id=1 not found in http://127.0.0.1:43369/ub/h/forceleader_test_collection due to: Path not found: /id; rsp={doc=null} [junit4] > at __randomizedtesting.SeedInfo.seed([96DE1B3AD690F101:70492FFAEF120860]:0) [junit4] > at org.apache.solr.cloud.HttpPartitionTest.assertDocExists(HttpPartitionTest.java:603) [junit4] > at org.apache.solr.cloud.HttpPartitionTest.assertDocsExistInAllReplicas(HttpPartitionTest.java:556) [junit4] > at org.apache.solr.cloud.ForceLeaderTest.testReplicasInLIRNoLeader(ForceLeaderTest.java:142) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1507903 ERROR (Thread-3122) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 1508080 ERROR (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1509353 WARN (qtp1046808147-15085) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1510535 WARN (OverseerThreadFactory-5624-thread-2) [ ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores. [junit4] 2> 1510804 ERROR (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1511973 WARN (qtp987918948-15132) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1512220 ERROR (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1513365 WARN (qtp1502231331-15166) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1513613 ERROR (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 1514762 WARN (qtp585412387-15198) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1514797 INFO (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLastPublishedStateIsActive [junit4] 2> 1516109 WARN (qtp1502231331-15159) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1516118 WARN (qtp987918948-15133) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1516139 WARN (qtp585412387-15199) [ ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 1526202 WARN (qtp1502231331-15160) [ ] o.a.s.h.a.CollectionsHandler Force leader attempt 1. Waiting 5 secs for an active leader. State of the slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "base_url":"http://127.0.0.1:34923/ub/h";, [junit4] 2> "node_name":"127.0.0.1:34923_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "leader":"true", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node5":{ [junit4] 2> "base_url":"http://127.0.0.1:46627/ub/h";, [junit4] 2> "node_name":"127.0.0.1:46627_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node6":{ [junit4] 2> "base_url":"http://127.0.0.1:40835/ub/h";, [junit4] 2> "node_name":"127.0.0.1:40835_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t3", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}}} [junit4] 2> 1531202 WARN (qtp1502231331-15160) [ ] o.a.s.h.a.CollectionsHandler Force leader attempt 2. Waiting 5 secs for an active leader. State of the slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "base_url":"http://127.0.0.1:34923/ub/h";, [junit4] 2> "node_name":"127.0.0.1:34923_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "leader":"true", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node5":{ [junit4] 2> "base_url":"http://127.0.0.1:46627/ub/h";, [junit4] 2> "node_name":"127.0.0.1:46627_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node6":{ [junit4] 2> "base_url":"http://127.0.0.1:40835/ub/h";, [junit4] 2> "node_name":"127.0.0.1:40835_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t3", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}}} [junit4] 2> 1536202 WARN (qtp1502231331-15160) [ ] o.a.s.h.a.CollectionsHandler Force leader attempt 3. Waiting 5 secs for an active leader. State of the slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "base_url":"http://127.0.0.1:34923/ub/h";, [junit4] 2> "node_name":"127.0.0.1:34923_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "leader":"true", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node5":{ [junit4] 2> "base_url":"http://127.0.0.1:46627/ub/h";, [junit4] 2> "node_name":"127.0.0.1:46627_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node6":{ [junit4] 2> "base_url":"http://127.0.0.1:40835/ub/h";, [junit4] 2> "node_name":"127.0.0.1:40835_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t3", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}}} [junit4] 2> 1541202 WARN (qtp1502231331-15160) [ ] o.a.s.h.a.CollectionsHandler Force leader attempt 4. Waiting 5 secs for an active leader. State of the slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "base_url":"http://127.0.0.1:34923/ub/h";, [junit4] 2> "node_name":"127.0.0.1:34923_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "leader":"true", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node5":{ [junit4] 2> "base_url":"http://127.0.0.1:46627/ub/h";, [junit4] 2> "node_name":"127.0.0.1:46627_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node6":{ [junit4] 2> "base_url":"http://127.0.0.1:40835/ub/h";, [junit4] 2> "node_name":"127.0.0.1:40835_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t3", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}}} [junit4] 2> 1546203 WARN (qtp1502231331-15160) [ ] o.a.s.h.a.CollectionsHandler Force leader attempt 5. Waiting 5 secs for an active leader. State of the slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "base_url":"http://127.0.0.1:34923/ub/h";, [junit4] 2> "node_name":"127.0.0.1:34923_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "leader":"true", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node5":{ [junit4] 2> "base_url":"http://127.0.0.1:46627/ub/h";, [junit4] 2> "node_name":"127.0.0.1:46627_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node6":{ [junit4] 2> "base_url":"http://127.0.0.1:40835/ub/h";, [junit4] 2> "node_name":"127.0.0.1:40835_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t3", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}}} [junit4] 2> 1551203 WARN (qtp1502231331-15160) [ ] o.a.s.h.a.CollectionsHandler Force leader attempt 6. Waiting 5 secs for an active leader. State of the slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "base_url":"http://127.0.0.1:34923/ub/h";, [junit4] 2> "node_name":"127.0.0.1:34923_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "leader":"true", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node5":{ [junit4] 2> "base_url":"http://127.0.0.1:46627/ub/h";, [junit4] 2> "node_name":"127.0.0.1:46627_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node6":{ [junit4] 2> "base_url":"http://127.0.0.1:40835/ub/h";, [junit4] 2> "node_name":"127.0.0.1:40835_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t3", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}}} [junit4] 2> 1556203 WARN (qtp1502231331-15160) [ ] o.a.s.h.a.CollectionsHandler Force leader attempt 7. Waiting 5 secs for an active leader. State of the slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "base_url":"http://127.0.0.1:34923/ub/h";, [junit4] 2> "node_name":"127.0.0.1:34923_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "leader":"true", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node5":{ [junit4] 2> "base_url":"http://127.0.0.1:46627/ub/h";, [junit4] 2> "node_name":"127.0.0.1:46627_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node6":{ [junit4] 2> "base_url":"http://127.0.0.1:40835/ub/h";, [junit4] 2> "node_name":"127.0.0.1:40835_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t3", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}}} [junit4] 2> 1561203 WARN (qtp1502231331-15160) [ ] o.a.s.h.a.CollectionsHandler Force leader attempt 8. Waiting 5 secs for an active leader. State of the slice: shard1:{ [junit4] 2> "range":"80000000-7fffffff", [junit4] 2> "state":"active", [junit4] 2> "replicas":{ [junit4] 2> "core_node4":{ [junit4] 2> "base_url":"http://127.0.0.1:34923/ub/h";, [junit4] 2> "node_name":"127.0.0.1:34923_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t1", [junit4] 2> "state":"down", [junit4] 2> "leader":"true", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node5":{ [junit4] 2> "base_url":"http://127.0.0.1:46627/ub/h";, [junit4] 2> "node_name":"127.0.0.1:46627_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t2", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}, [junit4] 2> "core_node6":{ [junit4] 2> "base_url":"http://127.0.0.1:40835/ub/h";, [junit4] 2> "node_name":"127.0.0.1:40835_ub%2Fh", [junit4] 2> "core":"forceleader_last_published_shard1_replica_t3", [junit4] 2> "state":"down", [junit4] 2> "type":"TLOG"}}} [ju [...truncated too long message...] d.LeaderElector.access$200(LeaderElector.java:56) [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269) [junit4] 2> at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1566220 WARN (zkCallback-3688-thread-1) [ ] o.a.s.c.LeaderElector [junit4] 2> org.apache.solr.common.SolrException: SolrCore not found:forceleader_last_published_shard1_replica_t3 in [collection1_shard2_replica_t45] [junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:297) [junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:170) [junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:135) [junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56) [junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:348) [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.lambda$process$0(SolrZkClient.java:269) [junit4] 2> at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [junit4] 2> at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 1566924 INFO (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLastPublishedStateIsActive [junit4] 2> 1568434 WARN (zkCallback-3664-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1569945 WARN (zkCallback-3676-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1569961 WARN (zkCallback-3682-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1570254 WARN (indexFetcher-5681-thread-1) [ ] o.a.s.h.ReplicationHandler I was asked to replicate but CoreContainer is shutting down [junit4] 2> 1570946 WARN (zkCallback-3688-thread-1) [ ] o.a.s.c.SyncStrategy Closed, skipping sync up. [junit4] 2> 1571467 WARN (zkCallback-3688-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 1571469 ERROR (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes [junit4] 2> 1576518 WARN (Thread-3122) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 6 /solr/aliases.json [junit4] 2> 4 /solr/security.json [junit4] 2> 4 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 6 /solr/clusterstate.json [junit4] 2> 6 /solr/clusterprops.json [junit4] 2> 3 /solr/collections/forceleader_last_published/state.json [junit4] 2> 3 /solr/collections/collection1/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 6 /solr/live_nodes [junit4] 2> 6 /solr/collections [junit4] 2> 4 /solr/overseer/queue [junit4] 2> 4 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> 1576519 WARN (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 5 connections to: http://127.0.0.1:34923/ub/h, target: http://127.0.0.1:42367/ub/h [junit4] 2> 1576519 WARN (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 4 connections to: http://127.0.0.1:40835/ub/h, target: http://127.0.0.1:35933/ub/h [junit4] 2> 1576519 WARN (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 3 connections to: http://127.0.0.1:36607/ub/h, target: http://127.0.0.1:43505/ub/h [junit4] 2> 1576519 WARN (TEST-ForceLeaderTest.testLastPublishedStateIsActive-seed#[96DE1B3AD690F101]) [ ] o.a.s.c.SocketProxy Closing 7 connections to: http://127.0.0.1:46627/ub/h, target: http://127.0.0.1:44391/ub/h [junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.ForceLeaderTest_96DE1B3AD690F101-001 [junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=975, maxDocsPerChunk=9, blockSize=1), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=975, blockSize=1)), sim=RandomSimilarity(queryNorm=true): {}, locale=sr-Latn-ME, timezone=Canada/Saskatchewan [junit4] 2> NOTE: Linux 4.10.0-27-generic amd64/Oracle Corporation 9 (64-bit)/cpus=8,threads=1,free=231627864,total=527958016 [junit4] 2> NOTE: All tests run in this JVM: [FileUtilsTest, ExitableDirectoryReaderTest, CSVRequestHandlerTest, TestLegacyFieldReuse, JsonLoaderTest, AlternateDirectoryTest, DistributedFacetPivotWhiteBoxTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, AtomicUpdatesTest, DirectSolrSpellCheckerTest, CoreAdminHandlerTest, ClusterStateUpdateTest, LeaderInitiatedRecoveryOnCommitTest, HdfsLockFactoryTest, TestPullReplica, TestDistributedGrouping, TestFreeTextSuggestions, TestLeaderInitiatedRecoveryThread, TestRandomRequestDistribution, SolrCloudExampleTest, TestComplexPhraseLeadingWildcard, TestCoreContainer, BlockJoinFacetDistribTest, TestBM25SimilarityFactory, TestSubQueryTransformerDistrib, ConnectionReuseTest, TestSweetSpotSimilarityFactory, RemoteQueryErrorTest, TestCloudManagedSchema, HdfsChaosMonkeySafeLeaderTest, TestTrieFacet, DistributedIntervalFacetingTest, TestRTGBase, TestGraphMLResponseWriter, TestSolrConfigHandlerConcurrent, V2StandaloneTest, MetricsConfigTest, ZkSolrClientTest, HighlighterTest, DOMUtilTest, TestSimpleTrackingShardHandler, TestFieldResource, TestInfoStreamLogging, TestCursorMarkWithoutUniqueKey, CurrencyFieldTypeTest, MissingSegmentRecoveryTest, TestCloudDeleteByQuery, HLLSerializationTest, SolrCmdDistributorTest, SystemInfoHandlerTest, JSONWriterTest, IndexSchemaRuntimeFieldTest, TestChildDocTransformer, ZkFailoverTest, VersionInfoTest, ChaosMonkeySafeLeaderTest, TestSolrIndexConfig, HdfsBasicDistributedZk2Test, TestSegmentSorting, TestSQLHandler, TestSolrCloudWithHadoopAuthPlugin, OpenCloseCoreStressTest, TestCustomDocTransformer, TestConfigSetProperties, ScriptEngineTest, TestPayloadScoreQParserPlugin, BlockDirectoryTest, TestSchemaNameResource, TestPostingsSolrHighlighter, TestCustomStream, LoggingHandlerTest, HdfsRecoverLeaseTest, DocValuesTest, MetricUtilsTest, AddBlockUpdateTest, BasicFunctionalityTest, CollectionsAPIDistributedZkTest, DistributedMLTComponentTest, TestReloadDeadlock, TestRawResponseWriter, SOLR749Test, ReplicaListTransformerTest, TestFiltering, TestNoOpRegenerator, HdfsUnloadDistributedZkTest, SolrMetricReporterTest, ConnectionManagerTest, MoveReplicaTest, TestSolrCLIRunExample, PropertiesRequestHandlerTest, TestPerFieldSimilarity, TestRestManager, ResponseHeaderTest, TestUnifiedSolrHighlighter, UpdateRequestProcessorFactoryTest, NoCacheHeaderTest, ConfigureRecoveryStrategyTest, TestFieldSortValues, TestCloudSearcherWarming, TestAtomicUpdateErrorCases, SolrSlf4jReporterTest, LukeRequestHandlerTest, AnalyticsMergeStrategyTest, TestMaxScoreQueryParser, TestExactSharedStatsCache, TestDistribDocBasedVersion, TestConfigSetsAPIZkFailure, TestReplicationHandlerBackup, BlobRepositoryCloudTest, TestConfigsApi, SharedFSAutoReplicaFailoverUtilsTest, HdfsSyncSliceTest, TestCoreDiscovery, TestAnalyzeInfixSuggestions, TestBackupRepositoryFactory, CloudMLTQParserTest, TestHashQParserPlugin, TestHdfsCloudBackupRestore, TestDFISimilarityFactory, OverseerTest, RecoveryZkTest, TestRandomFaceting, TestZkChroot, ZkCLITest, TestFaceting, TestRecovery, ZkControllerTest, TestJoin, TestSolr4Spatial, StatsComponentTest, QueryElevationComponentTest, BadIndexSchemaTest, ConvertedLegacyTest, SoftAutoCommitTest, TestTolerantSearch, TestEmbeddedSolrServerConstructors, TestJettySolrRunner, ActionThrottleTest, AssignBackwardCompatibilityTest, CleanupOldIndexTest, CollectionStateFormat2Test, CollectionsAPIAsyncDistributedZkTest, DeleteLastCustomShardedReplicaTest, DeleteShardTest, DistribCursorPagingTest, ForceLeaderTest] [junit4] Completed [522/732 (3!)] on J1 in 103.83s, 3 tests, 1 failure, 1 skipped <<< FAILURES! [...truncated 37234 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
