lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-NightlyTests-master - Build # 1840 - Still Unstable
Date Sat, 04 May 2019 15:53:07 GMT
Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-master/1840/

4 tests failed.
FAILED:  org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings

Error Message:
stage 2: inconsistent endOffset at pos=1: 12 vs 14; token=bz<EMOJI>禍 ﺶ

Stack Trace:
java.lang.IllegalStateException: stage 2: inconsistent endOffset at pos=1: 12 vs 14; token=bz<EMOJI>禍 ﺶ
	at __randomizedtesting.SeedInfo.seed([FD335D36B1469C78:9768E227E808BC8B]:0)
	at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:146)
	at org.apache.lucene.analysis.snowball.SnowballFilter.incrementToken(SnowballFilter.java:90)
	at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:77)
	at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
	at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
	at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
	at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:899)
	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:566)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
	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:947)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
	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:834)


FAILED:  org.apache.solr.cloud.OverseerRolesTest.testDesignatedOverseerRestarts

Error Message:
Timed out waiting for overseer state change. The current overseer is: 127.0.0.1:43623_solr

Stack Trace:
java.lang.AssertionError: Timed out waiting for overseer state change. The current overseer is: 127.0.0.1:43623_solr
	at __randomizedtesting.SeedInfo.seed([92D96B978233C3FD:9AF87CC8BC6DD1F7]:0)
	at org.junit.Assert.fail(Assert.java:88)
	at org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:70)
	at org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:75)
	at org.apache.solr.cloud.OverseerRolesTest.testDesignatedOverseerRestarts(OverseerRolesTest.java:188)
	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:566)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
	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:947)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
	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:834)


FAILED:  org.apache.solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest.test

Error Message:
Test abandoned because suite timeout was reached.

Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
	at __randomizedtesting.SeedInfo.seed([92D96B978233C3FD]:0)


FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.hdfs.HdfsTlogReplayBufferedWhileIndexingTest

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
	at __randomizedtesting.SeedInfo.seed([92D96B978233C3FD]:0)




Build Log:
[...truncated 3715 lines...]
   [junit4] Suite: org.apache.lucene.analysis.core.TestRandomChains
   [junit4]   2> stage 0: bz<[0-2] +1> 禍<[11-12] +1> ﺶ<[13-14] +1> t<[18-19] +1> uv<[20-22] +1> 
   [junit4]   2> stage 1: bz<[0-2] +1> bz 禍<[0-12] +0> bz 禍 ﺶ<[0-14] +0> bz 禍 ﺶ t<[0-19] +0> bz 禍 ﺶ t uv<[0-22] +0> 禍<[11-12] +1> 禍 ﺶ<[11-14] +0> 
   [junit4]   2> stage 2: bz<EMOJI>禍<[0-12] +1> bz<EMOJI>禍 ﺶ<[0-14] +0> 
   [junit4]   2> TEST FAIL: useCharFilter=true text='bz  \u205a\u201b\u2063\u205a\u200c\u201c \ufa52,\ufeb6\ue187\ue9dd  t uv'
   [junit4]   2> Exception from random analyzer: 
   [junit4]   2> charfilters=
   [junit4]   2>   org.apache.lucene.analysis.MockCharFilter(java.io.StringReader@1a956f79)
   [junit4]   2> tokenizer=
   [junit4]   2>   org.apache.lucene.analysis.standard.StandardTokenizer(org.apache.lucene.util.AttributeFactory$1@5220a7c6)
   [junit4]   2> filters=
   [junit4]   2>   org.apache.lucene.analysis.shingle.ShingleFilter(ValidatingTokenFilter@216992bf term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1, 16)
   [junit4]   2>   org.apache.lucene.analysis.shingle.FixedShingleFilter(ValidatingTokenFilter@23c8533c term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1, 2, <EMOJI>, <HIRAGANA>)
   [junit4]   2>   org.apache.lucene.analysis.snowball.SnowballFilter(ValidatingTokenFilter@72d37dee term=,bytes=[],startOffset=0,endOffset=0,positionIncrement=1,positionLength=1,type=word,termFrequency=1,keyword=false, org.tartarus.snowball.ext.DanishStemmer@e7b8f9b1)
   [junit4]   2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory.
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestRandomChains -Dtests.method=testRandomChainsWithLargeStrings -Dtests.seed=FD335D36B1469C78 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/test-data/enwiki.random.lines.txt -Dtests.locale=en-CY -Dtests.timezone=Europe/Bratislava -Dtests.asserts=true -Dtests.file.encoding=UTF-8
   [junit4] ERROR   16.7s J2 | TestRandomChains.testRandomChainsWithLargeStrings <<<
   [junit4]    > Throwable #1: java.lang.IllegalStateException: stage 2: inconsistent endOffset at pos=1: 12 vs 14; token=bz<EMOJI>禍 ﺶ
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([FD335D36B1469C78:9768E227E808BC8B]:0)
   [junit4]    > 	at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:146)
   [junit4]    > 	at org.apache.lucene.analysis.snowball.SnowballFilter.incrementToken(SnowballFilter.java:90)
   [junit4]    > 	at org.apache.lucene.analysis.ValidatingTokenFilter.incrementToken(ValidatingTokenFilter.java:77)
   [junit4]    > 	at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:746)
   [junit4]    > 	at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:657)
   [junit4]    > 	at org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:559)
   [junit4]    > 	at org.apache.lucene.analysis.core.TestRandomChains.testRandomChainsWithLargeStrings(TestRandomChains.java:899)
   [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:566)
   [junit4]    > 	at java.base/java.lang.Thread.run(Thread.java:834)
   [junit4]   2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/build/analysis/common/test/J2/temp/lucene.analysis.core.TestRandomChains_FD335D36B1469C78-001
   [junit4]   2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4d1f01ae), locale=en-CY, timezone=Europe/Bratislava
   [junit4]   2> NOTE: Linux 4.4.0-137-generic amd64/Oracle Corporation 11.0.1 (64-bit)/cpus=4,threads=1,free=135501248,total=264241152
   [junit4]   2> NOTE: All tests run in this JVM: [TestBengaliFilters, TestSwedishAnalyzer, TestFingerprintFilter, TestSoraniStemFilter, TestComplexPrefix, TestMorphData, TestStemmerOverrideFilter, TestLimitTokenOffsetFilterFactory, TestSpanishLightStemFilterFactory, TestTeeSinkTokenFilter, TestLengthFilter, TestNorwegianMinimalStemFilter, TestStrangeOvergeneration, TestLatvianAnalyzer, TestHindiNormalizer, TestIrishLowerCaseFilter, TestFrenchAnalyzer, TestTrimFilterFactory, TestRandomChains]
   [junit4] Completed [212/291 (1!)] on J2 in 192.17s, 2 tests, 1 error <<< FAILURES!

[...truncated 10628 lines...]
   [junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
   [junit4]   2> 2549598 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerRolesTest_92D96B978233C3FD-001/init-core-data-001
   [junit4]   2> 2549599 WARN  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=20 numCloses=20
   [junit4]   2> 2549599 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 2549600 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
   [junit4]   2> 2549600 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 6 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerRolesTest_92D96B978233C3FD-001/tempDir-001
   [junit4]   2> 2549600 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 2549601 INFO  (ZkTestServer Run Thread) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 2549601 INFO  (ZkTestServer Run Thread) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 2549701 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:43085
   [junit4]   2> 2549701 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:43085
   [junit4]   2> 2549701 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 43085
   [junit4]   2> 2549709 INFO  (zkConnectionManagerCallback-14760-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549712 INFO  (zkConnectionManagerCallback-14762-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549714 INFO  (zkConnectionManagerCallback-14764-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549717 WARN  (jetty-launcher-14765-thread-1) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2549717 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2549717 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2549718 INFO  (jetty-launcher-14765-thread-1) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
   [junit4]   2> 2549722 WARN  (jetty-launcher-14765-thread-2) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2549722 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2549722 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2549722 INFO  (jetty-launcher-14765-thread-2) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
   [junit4]   2> 2549723 WARN  (jetty-launcher-14765-thread-3) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2549723 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2549723 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2549723 INFO  (jetty-launcher-14765-thread-3) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
   [junit4]   2> 2549724 WARN  (jetty-launcher-14765-thread-4) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2549724 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2549724 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2549724 INFO  (jetty-launcher-14765-thread-4) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
   [junit4]   2> 2549725 WARN  (jetty-launcher-14765-thread-5) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2549725 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2549725 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2549725 INFO  (jetty-launcher-14765-thread-5) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
   [junit4]   2> 2549727 INFO  (jetty-launcher-14765-thread-1) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2549727 INFO  (jetty-launcher-14765-thread-1) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2549727 INFO  (jetty-launcher-14765-thread-1) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2549727 INFO  (jetty-launcher-14765-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d032e17{/solr,null,AVAILABLE}
   [junit4]   2> 2549728 INFO  (jetty-launcher-14765-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@732b06b2{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:38598}
   [junit4]   2> 2549728 INFO  (jetty-launcher-14765-thread-1) [    ] o.e.j.s.Server Started @2549774ms
   [junit4]   2> 2549728 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=38598}
   [junit4]   2> 2549728 ERROR (jetty-launcher-14765-thread-1) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2549728 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2549729 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 2549729 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2549729 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2549729 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-05-04T12:58:04.968080Z
   [junit4]   2> 2549731 INFO  (jetty-launcher-14765-thread-5) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2549731 INFO  (jetty-launcher-14765-thread-5) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2549731 INFO  (jetty-launcher-14765-thread-5) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2549731 INFO  (jetty-launcher-14765-thread-5) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7afd3719{/solr,null,AVAILABLE}
   [junit4]   2> 2549732 INFO  (jetty-launcher-14765-thread-5) [    ] o.e.j.s.AbstractConnector Started ServerConnector@5942bd3f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:34615}
   [junit4]   2> 2549732 INFO  (jetty-launcher-14765-thread-5) [    ] o.e.j.s.Server Started @2549778ms
   [junit4]   2> 2549732 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34615}
   [junit4]   2> 2549732 ERROR (jetty-launcher-14765-thread-5) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2549732 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2549732 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 2549732 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2549732 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2549732 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-05-04T12:58:04.971508Z
   [junit4]   2> 2549733 WARN  (jetty-launcher-14765-thread-6) [    ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
   [junit4]   2> 2549734 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
   [junit4]   2> 2549734 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
   [junit4]   2> 2549734 INFO  (jetty-launcher-14765-thread-6) [    ] o.e.j.s.Server jetty-9.4.14.v20181114; built: 2018-11-14T21:20:31.478Z; git: c4550056e785fb5665914545889f21dc136ad9e6; jvm 11.0.1+13-LTS
   [junit4]   2> 2549735 INFO  (jetty-launcher-14765-thread-4) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2549735 INFO  (jetty-launcher-14765-thread-4) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2549735 INFO  (jetty-launcher-14765-thread-4) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2549736 INFO  (jetty-launcher-14765-thread-4) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@195d10a0{/solr,null,AVAILABLE}
   [junit4]   2> 2549736 INFO  (jetty-launcher-14765-thread-4) [    ] o.e.j.s.AbstractConnector Started ServerConnector@48e0806f{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37849}
   [junit4]   2> 2549736 INFO  (jetty-launcher-14765-thread-4) [    ] o.e.j.s.Server Started @2549783ms
   [junit4]   2> 2549736 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37849}
   [junit4]   2> 2549737 ERROR (jetty-launcher-14765-thread-4) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2549737 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2549737 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 2549737 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2549737 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2549737 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-05-04T12:58:04.976443Z
   [junit4]   2> 2549739 INFO  (jetty-launcher-14765-thread-3) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2549739 INFO  (jetty-launcher-14765-thread-3) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2549739 INFO  (jetty-launcher-14765-thread-3) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2549739 INFO  (jetty-launcher-14765-thread-3) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@544137cc{/solr,null,AVAILABLE}
   [junit4]   2> 2549740 INFO  (jetty-launcher-14765-thread-3) [    ] o.e.j.s.AbstractConnector Started ServerConnector@1b1ca32a{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:43623}
   [junit4]   2> 2549740 INFO  (jetty-launcher-14765-thread-3) [    ] o.e.j.s.Server Started @2549786ms
   [junit4]   2> 2549740 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=43623}
   [junit4]   2> 2549740 ERROR (jetty-launcher-14765-thread-3) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2549740 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2549740 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 2549740 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2549740 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2549740 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-05-04T12:58:04.979686Z
   [junit4]   2> 2549742 INFO  (jetty-launcher-14765-thread-2) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2549742 INFO  (jetty-launcher-14765-thread-2) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2549742 INFO  (jetty-launcher-14765-thread-2) [    ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 2549743 INFO  (jetty-launcher-14765-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2e13890d{/solr,null,AVAILABLE}
   [junit4]   2> 2549743 INFO  (jetty-launcher-14765-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@1df0edf5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:45582}
   [junit4]   2> 2549743 INFO  (jetty-launcher-14765-thread-2) [    ] o.e.j.s.Server Started @2549789ms
   [junit4]   2> 2549743 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45582}
   [junit4]   2> 2549743 ERROR (jetty-launcher-14765-thread-2) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2549743 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2549744 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 2549744 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2549744 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2549744 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-05-04T12:58:04.983068Z
   [junit4]   2> 2549747 INFO  (zkConnectionManagerCallback-14771-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549747 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2549770 INFO  (zkConnectionManagerCallback-14769-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549771 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2549771 INFO  (zkConnectionManagerCallback-14773-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549772 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2549790 INFO  (zkConnectionManagerCallback-14767-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549797 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2549803 INFO  (jetty-launcher-14765-thread-6) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 2549803 INFO  (jetty-launcher-14765-thread-6) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 2549803 INFO  (jetty-launcher-14765-thread-6) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 2549804 INFO  (jetty-launcher-14765-thread-6) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3233d3a{/solr,null,AVAILABLE}
   [junit4]   2> 2549813 INFO  (jetty-launcher-14765-thread-6) [    ] o.e.j.s.AbstractConnector Started ServerConnector@3f8c05cc{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:44741}
   [junit4]   2> 2549813 INFO  (jetty-launcher-14765-thread-6) [    ] o.e.j.s.Server Started @2549859ms
   [junit4]   2> 2549813 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44741}
   [junit4]   2> 2549813 ERROR (jetty-launcher-14765-thread-6) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 2549813 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 2549814 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 2549814 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 2549814 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 2549814 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2019-05-04T12:58:05.053065Z
   [junit4]   2> 2549821 INFO  (zkConnectionManagerCallback-14775-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549821 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2549830 INFO  (zkConnectionManagerCallback-14781-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549831 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 2549903 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2549904 WARN  (jetty-launcher-14765-thread-3) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@6829a38c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2549907 WARN  (jetty-launcher-14765-thread-3) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3e07079c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2549908 INFO  (jetty-launcher-14765-thread-3) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43085/solr
   [junit4]   2> 2549923 INFO  (zkConnectionManagerCallback-14789-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2549949 INFO  (zkConnectionManagerCallback-14791-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550075 INFO  (jetty-launcher-14765-thread-3) [n:127.0.0.1:43623_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:43623_solr
   [junit4]   2> 2550076 INFO  (jetty-launcher-14765-thread-3) [n:127.0.0.1:43623_solr    ] o.a.s.c.Overseer Overseer (id=73244414069571594-127.0.0.1:43623_solr-n_0000000000) starting
   [junit4]   2> 2550097 INFO  (zkConnectionManagerCallback-14798-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550100 INFO  (jetty-launcher-14765-thread-3) [n:127.0.0.1:43623_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43085/solr ready
   [junit4]   2> 2550102 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2550103 INFO  (OverseerStateUpdate-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:43623_solr
   [junit4]   2> 2550103 INFO  (jetty-launcher-14765-thread-3) [n:127.0.0.1:43623_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43623_solr
   [junit4]   2> 2550105 INFO  (OverseerStateUpdate-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2550106 WARN  (jetty-launcher-14765-thread-1) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@724c28ae[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550128 WARN  (jetty-launcher-14765-thread-1) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@72b0571[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550129 INFO  (jetty-launcher-14765-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43085/solr
   [junit4]   2> 2550137 INFO  (zkCallback-14797-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2550146 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2550157 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2550157 INFO  (jetty-launcher-14765-thread-3) [n:127.0.0.1:43623_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2550168 INFO  (zkConnectionManagerCallback-14805-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550173 WARN  (jetty-launcher-14765-thread-4) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1fdbfc15[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550186 WARN  (jetty-launcher-14765-thread-5) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@278aa43d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550192 INFO  (jetty-launcher-14765-thread-3) [n:127.0.0.1:43623_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43623.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550210 INFO  (jetty-launcher-14765-thread-3) [n:127.0.0.1:43623_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43623.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550210 INFO  (jetty-launcher-14765-thread-3) [n:127.0.0.1:43623_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_43623.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550212 INFO  (jetty-launcher-14765-thread-3) [n:127.0.0.1:43623_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerRolesTest_92D96B978233C3FD-001/tempDir-001/node3/.
   [junit4]   2> 2550213 INFO  (zkConnectionManagerCallback-14809-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550219 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 2550224 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.c.ZkController Publish node=127.0.0.1:38598_solr as DOWN
   [junit4]   2> 2550226 WARN  (jetty-launcher-14765-thread-4) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@245f491d[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550228 INFO  (jetty-launcher-14765-thread-4) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43085/solr
   [junit4]   2> 2550228 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2550228 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38598_solr
   [junit4]   2> 2550229 WARN  (jetty-launcher-14765-thread-5) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@dba4e85[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550230 INFO  (zkCallback-14790-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2550231 INFO  (jetty-launcher-14765-thread-5) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43085/solr
   [junit4]   2> 2550233 INFO  (zkCallback-14797-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2550244 INFO  (zkCallback-14808-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 2550269 INFO  (zkConnectionManagerCallback-14821-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550270 INFO  (zkConnectionManagerCallback-14826-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550271 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2550272 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43085/solr ready
   [junit4]   2> 2550277 INFO  (zkConnectionManagerCallback-14818-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550281 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2550286 INFO  (zkConnectionManagerCallback-14828-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550287 INFO  (zkConnectionManagerCallback-14831-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550306 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2550306 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 2550315 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.c.ZkController Publish node=127.0.0.1:37849_solr as DOWN
   [junit4]   2> 2550315 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.c.ZkController Publish node=127.0.0.1:34615_solr as DOWN
   [junit4]   2> 2550317 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2550317 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37849_solr
   [junit4]   2> 2550317 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2550317 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34615_solr
   [junit4]   2> 2550319 INFO  (zkCallback-14808-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2550320 INFO  (zkCallback-14797-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2550320 INFO  (zkCallback-14790-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2550329 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38598.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550336 INFO  (zkCallback-14827-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2550336 INFO  (zkCallback-14830-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2550348 INFO  (zkCallback-14825-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
   [junit4]   2> 2550349 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38598.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550350 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38598.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550352 INFO  (jetty-launcher-14765-thread-1) [n:127.0.0.1:38598_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerRolesTest_92D96B978233C3FD-001/tempDir-001/node1/.
   [junit4]   2> 2550375 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2550380 WARN  (jetty-launcher-14765-thread-2) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@14f79e87[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550385 WARN  (jetty-launcher-14765-thread-2) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@30eb9975[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550386 INFO  (jetty-launcher-14765-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43085/solr
   [junit4]   2> 2550386 INFO  (zkConnectionManagerCallback-14840-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550388 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2550389 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43085/solr ready
   [junit4]   2> 2550390 INFO  (zkConnectionManagerCallback-14845-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550390 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2550392 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2550393 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43085/solr ready
   [junit4]   2> 2550405 INFO  (zkConnectionManagerCallback-14851-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550406 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2550420 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34615.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550440 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34615.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550440 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34615.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550440 INFO  (zkConnectionManagerCallback-14855-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550443 INFO  (jetty-launcher-14765-thread-5) [n:127.0.0.1:34615_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerRolesTest_92D96B978233C3FD-001/tempDir-001/node5/.
   [junit4]   2> 2550446 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37849.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550452 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 2550456 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.c.ZkController Publish node=127.0.0.1:45582_solr as DOWN
   [junit4]   2> 2550457 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 2550458 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2550458 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45582_solr
   [junit4]   2> 2550459 WARN  (jetty-launcher-14765-thread-6) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@56cd5839[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550459 INFO  (zkCallback-14797-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2550460 INFO  (zkCallback-14825-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2550461 INFO  (zkCallback-14839-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2550461 INFO  (zkCallback-14827-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2550461 INFO  (zkCallback-14808-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2550461 INFO  (zkCallback-14830-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2550461 INFO  (zkCallback-14790-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2550462 INFO  (zkCallback-14844-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2550462 WARN  (jetty-launcher-14765-thread-6) [    ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3cf7b3dd[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 2550464 INFO  (jetty-launcher-14765-thread-6) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43085/solr
   [junit4]   2> 2550465 INFO  (zkCallback-14854-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
   [junit4]   2> 2550466 INFO  (zkConnectionManagerCallback-14863-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550474 INFO  (zkConnectionManagerCallback-14865-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550481 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 2550485 INFO  (zkConnectionManagerCallback-14872-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550487 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.c.ZkController Publish node=127.0.0.1:44741_solr as DOWN
   [junit4]   2> 2550487 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
   [junit4]   2> 2550488 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 2550488 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44741_solr
   [junit4]   2> 2550490 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43085/solr ready
   [junit4]   2> 2550490 INFO  (zkCallback-14797-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550490 INFO  (zkCallback-14839-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550490 INFO  (zkCallback-14790-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550490 INFO  (zkCallback-14808-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550491 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2550491 INFO  (zkCallback-14830-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550491 INFO  (zkCallback-14854-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550491 INFO  (zkCallback-14844-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550491 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37849.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550491 INFO  (zkCallback-14871-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550491 INFO  (zkCallback-14825-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550492 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37849.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550492 INFO  (zkCallback-14827-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550494 INFO  (zkCallback-14864-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (5) -> (6)
   [junit4]   2> 2550495 INFO  (jetty-launcher-14765-thread-4) [n:127.0.0.1:37849_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerRolesTest_92D96B978233C3FD-001/tempDir-001/node4/.
   [junit4]   2> 2550523 INFO  (zkConnectionManagerCallback-14878-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2550525 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (6)
   [junit4]   2> 2550527 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43085/solr ready
   [junit4]   2> 2550527 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 2550528 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45582.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550583 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45582.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550583 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45582.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550586 INFO  (jetty-launcher-14765-thread-2) [n:127.0.0.1:45582_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerRolesTest_92D96B978233C3FD-001/tempDir-001/node2/.
   [junit4]   2> 2550599 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44741.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550622 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44741.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550622 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44741.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@38a3f1f4
   [junit4]   2> 2550624 INFO  (jetty-launcher-14765-thread-6) [n:127.0.0.1:44741_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerRolesTest_92D96B978233C3FD-001/tempDir-001/node6/.
   [junit4]   2> 2551033 INFO  (zkConnectionManagerCallback-14884-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 2551035 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (6)
   [junit4]   2> 2551036 INFO  (SUITE-OverseerRolesTest-seed#[92D96B978233C3FD]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43085/solr ready
   [junit4]   2> 2551097 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testOverseerRole
   [junit4]   2> 2551098 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:43623_solr
   [junit4]   2> 2551098 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest Election queue: 
   [junit4]   2> 2551104 INFO  (qtp1230383851-38632) [n:127.0.0.1:44741_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:43623_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551110 INFO  (OverseerThreadFactory-10708-thread-1-processing-n:127.0.0.1:43623_solr) [n:127.0.0.1:43623_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:43623_solr
   [junit4]   2> 2551120 INFO  (qtp1230383851-38632) [n:127.0.0.1:44741_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:43623_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=17
   [junit4]   2> 2551121 INFO  (qtp1230383851-38634) [n:127.0.0.1:44741_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:38598_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551129 INFO  (OverseerCollectionConfigSetProcessor-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 2551133 INFO  (OverseerThreadFactory-10708-thread-2-processing-n:127.0.0.1:43623_solr) [n:127.0.0.1:43623_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:38598_solr
   [junit4]   2> 2551136 INFO  (qtp1230383851-38634) [n:127.0.0.1:44741_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:38598_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=14
   [junit4]   2> 2551137 INFO  (qtp1230383851-38635) [n:127.0.0.1:44741_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:37849_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551158 INFO  (OverseerCollectionConfigSetProcessor-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 2551159 INFO  (OverseerThreadFactory-10708-thread-3-processing-n:127.0.0.1:43623_solr) [n:127.0.0.1:43623_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:37849_solr
   [junit4]   2> 2551168 INFO  (qtp1230383851-38635) [n:127.0.0.1:44741_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:37849_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=31
   [junit4]   2> 2551169 INFO  (qtp1230383851-38636) [n:127.0.0.1:44741_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:34615_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551173 INFO  (OverseerCollectionConfigSetProcessor-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 2551173 INFO  (OverseerThreadFactory-10708-thread-4-processing-n:127.0.0.1:43623_solr) [n:127.0.0.1:43623_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:34615_solr
   [junit4]   2> 2551183 INFO  (qtp1230383851-38636) [n:127.0.0.1:44741_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:34615_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=14
   [junit4]   2> 2551184 INFO  (qtp1230383851-38631) [n:127.0.0.1:44741_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:45582_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551187 INFO  (OverseerCollectionConfigSetProcessor-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 2551187 INFO  (OverseerThreadFactory-10708-thread-5-processing-n:127.0.0.1:43623_solr) [n:127.0.0.1:43623_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:45582_solr
   [junit4]   2> 2551191 INFO  (qtp1230383851-38631) [n:127.0.0.1:44741_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:45582_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 2551193 INFO  (qtp1230383851-38632) [n:127.0.0.1:44741_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:44741_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551208 INFO  (OverseerCollectionConfigSetProcessor-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 2551208 INFO  (OverseerThreadFactory-10708-thread-5-processing-n:127.0.0.1:43623_solr) [n:127.0.0.1:43623_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:44741_solr
   [junit4]   2> 2551219 INFO  (qtp1230383851-38632) [n:127.0.0.1:44741_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:44741_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=26
   [junit4]   2> 2551220 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest ### Setting overseer designate 127.0.0.1:45582_solr
   [junit4]   2> 2551222 INFO  (qtp1230383851-38634) [n:127.0.0.1:44741_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:45582_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551225 INFO  (OverseerCollectionConfigSetProcessor-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000010 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 2551225 INFO  (OverseerThreadFactory-10708-thread-5-processing-n:127.0.0.1:43623_solr) [n:127.0.0.1:43623_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to 127.0.0.1:45582_solr
   [junit4]   2> 2551235 INFO  (qtp1230383851-38634) [n:127.0.0.1:44741_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:45582_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=13
   [junit4]   2> 2551236 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest Expecting node: 127.0.0.1:45582_solr
   [junit4]   2> 2551242 INFO  (Thread-7667) [    ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 73244414069571594-127.0.0.1:43623_solr-n_0000000000 overseer designates are [127.0.0.1:45582_solr]
   [junit4]   2> 2551242 INFO  (Thread-7667) [    ] o.a.s.c.OverseerNodePrioritizer sorted nodes [73244414069571594-127.0.0.1:43623_solr-n_0000000000, 73244414069571597-127.0.0.1:38598_solr-n_0000000001, 73244414069571602-127.0.0.1:37849_solr-n_0000000002, 73244414069571601-127.0.0.1:34615_solr-n_0000000003, 73244414069571606-127.0.0.1:45582_solr-n_0000000004, 73244414069571608-127.0.0.1:44741_solr-n_0000000005]
   [junit4]   2> 2551242 INFO  (Thread-7667) [    ] o.a.s.c.OverseerNodePrioritizer asking node 73244414069571606-127.0.0.1:45582_solr-n_0000000004 to come join election at head
   [junit4]   2> 2551248 INFO  (qtp572013249-38619) [n:127.0.0.1:45582_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=73244414069571606-127.0.0.1:45582_solr-n_0000000004&wt=javabin&version=2} status=0 QTime=3
   [junit4]   2> 2551248 INFO  (Thread-7667) [    ] o.a.s.c.OverseerNodePrioritizer asking the old first in line 73244414069571597-127.0.0.1:38598_solr-n_0000000001 to rejoin election  
   [junit4]   2> 2551259 INFO  (qtp1205448589-38578) [n:127.0.0.1:38598_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=73244414069571597-127.0.0.1:38598_solr-n_0000000001&wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 2551261 INFO  (OverseerStateUpdate-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.Overseer Quit command received {
   [junit4]   2>   "operation":"quit",
   [junit4]   2>   "id":"73244414069571594-127.0.0.1:43623_solr-n_0000000000"} 127.0.0.1:43623_solr
   [junit4]   2> 2551262 INFO  (OverseerStateUpdate-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [n:127.0.0.1:43623_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:43623_solr
   [junit4]   2> 2551273 WARN  (OverseerExitThread) [    ] o.a.s.c.Overseer I (id=73244414069571594-127.0.0.1:43623_solr-n_0000000000) am exiting, but I'm still the leader
   [junit4]   2> 2551274 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571594-127.0.0.1:43623_solr-n_0000000000) closing
   [junit4]   2> 2551274 INFO  (zkCallback-14854-thread-1) [    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45582_solr
   [junit4]   2> 2551275 INFO  (OverseerAutoScalingTriggerThread-73244414069571594-127.0.0.1:43623_solr-n_0000000000) [    ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 2551276 INFO  (zkCallback-14854-thread-1) [n:127.0.0.1:45582_solr    ] o.a.s.c.Overseer Overseer (id=73244414069571606-127.0.0.1:45582_solr-n_0000000001) starting
   [junit4]   2> 2551279 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571594-127.0.0.1:43623_solr-n_0000000000) closing
   [junit4]   2> 2551279 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571594-127.0.0.1:43623_solr-n_0000000000) closing
   [junit4]   2> 2551280 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571594-127.0.0.1:43623_solr-n_0000000000) closing
   [junit4]   2> 2551284 INFO  (OverseerStateUpdate-73244414069571606-127.0.0.1:45582_solr-n_0000000001) [n:127.0.0.1:45582_solr    ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:45582_solr
   [junit4]   2> 2551342 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest ### Adding another overseer designate 127.0.0.1:44741_solr
   [junit4]   2> 2551343 INFO  (qtp1230383851-38635) [n:127.0.0.1:44741_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:44741_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551348 INFO  (OverseerThreadFactory-10748-thread-1-processing-n:127.0.0.1:45582_solr) [n:127.0.0.1:45582_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to 127.0.0.1:44741_solr
   [junit4]   2> 2551351 INFO  (qtp1230383851-38635) [n:127.0.0.1:44741_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:44741_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=8
   [junit4]   2> 2551352 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:45582_solr
   [junit4]   2> 2551353 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest Election queue: 
   [junit4]   2> 2551356 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1896992623
   [junit4]   2> 2551356 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45582_solr
   [junit4]   2> 2551357 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 2551357 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.ZkController Publish node=127.0.0.1:45582_solr as DOWN
   [junit4]   2> 2551362 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
   [junit4]   2> 2551362 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@31a4997e: rootName = solr_45582, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@364e0546
   [junit4]   2> 2551378 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
   [junit4]   2> 2551378 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7ba35091: rootName = solr_45582, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@39988018
   [junit4]   2> 2551386 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
   [junit4]   2> 2551386 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@56a71881: rootName = solr_45582, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7df0a1dd
   [junit4]   2> 2551386 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
   [junit4]   2> 2551389 INFO  (closeThreadPool-14888-thread-2) [    ] o.a.s.c.Overseer Overseer (id=73244414069571606-127.0.0.1:45582_solr-n_0000000001) closing
   [junit4]   2> 2551389 INFO  (OverseerStateUpdate-73244414069571606-127.0.0.1:45582_solr-n_0000000001) [n:127.0.0.1:45582_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:45582_solr
   [junit4]   2> 2551392 INFO  (OverseerAutoScalingTriggerThread-73244414069571606-127.0.0.1:45582_solr-n_0000000001) [    ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 2551395 INFO  (closeThreadPool-14888-thread-1) [    ] o.a.s.c.Overseer Overseer (id=73244414069571606-127.0.0.1:45582_solr-n_0000000001) closing
   [junit4]   2> 2551396 INFO  (zkCallback-14839-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551396 INFO  (zkCallback-14797-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551396 INFO  (zkCallback-14830-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551396 INFO  (zkCallback-14827-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551396 INFO  (zkCallback-14808-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551397 INFO  (zkCallback-14864-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551397 INFO  (zkCallback-14825-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551397 INFO  (zkCallback-14844-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551398 INFO  (zkCallback-14790-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551398 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.Overseer Overseer (id=73244414069571606-127.0.0.1:45582_solr-n_0000000001) closing
   [junit4]   2> 2551398 INFO  (zkCallback-14883-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551400 INFO  (zkCallback-14877-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (6) -> (5)
   [junit4]   2> 2551401 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.e.j.s.AbstractConnector Stopped ServerConnector@1df0edf5{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
   [junit4]   2> 2551401 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2e13890d{/solr,null,UNAVAILABLE}
   [junit4]   2> 2551401 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.e.j.s.session node0 Stopped scavenging
   [junit4]   2> 2551402 INFO  (zkCallback-14830-thread-2) [    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37849_solr
   [junit4]   2> 2551402 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest Expecting node: 127.0.0.1:44741_solr
   [junit4]   2> 2551403 INFO  (zkCallback-14830-thread-2) [n:127.0.0.1:37849_solr    ] o.a.s.c.Overseer Overseer (id=73244414069571602-127.0.0.1:37849_solr-n_0000000002) starting
   [junit4]   2> 2551419 INFO  (OverseerStateUpdate-73244414069571602-127.0.0.1:37849_solr-n_0000000002) [n:127.0.0.1:37849_solr    ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37849_solr
   [junit4]   2> 2551428 INFO  (OverseerCollectionConfigSetProcessor-73244414069571602-127.0.0.1:37849_solr-n_0000000002) [n:127.0.0.1:37849_solr    ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 73244414069571602-127.0.0.1:37849_solr-n_0000000002 overseer designates are [127.0.0.1:45582_solr, 127.0.0.1:44741_solr]
   [junit4]   2> 2551429 INFO  (OverseerCollectionConfigSetProcessor-73244414069571602-127.0.0.1:37849_solr-n_0000000002) [n:127.0.0.1:37849_solr    ] o.a.s.c.OverseerNodePrioritizer sorted nodes [73244414069571602-127.0.0.1:37849_solr-n_0000000002, 73244414069571601-127.0.0.1:34615_solr-n_0000000003, 73244414069571608-127.0.0.1:44741_solr-n_0000000005, 73244414069571597-127.0.0.1:38598_solr-n_0000000007, 73244414069571594-127.0.0.1:43623_solr-n_0000000008]
   [junit4]   2> 2551429 INFO  (OverseerCollectionConfigSetProcessor-73244414069571602-127.0.0.1:37849_solr-n_0000000002) [n:127.0.0.1:37849_solr    ] o.a.s.c.OverseerNodePrioritizer asking node 73244414069571608-127.0.0.1:44741_solr-n_0000000005 to come join election at head
   [junit4]   2> 2551440 INFO  (qtp1230383851-38631) [n:127.0.0.1:44741_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=73244414069571608-127.0.0.1:44741_solr-n_0000000005&wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 2551440 INFO  (OverseerCollectionConfigSetProcessor-73244414069571602-127.0.0.1:37849_solr-n_0000000002) [n:127.0.0.1:37849_solr    ] o.a.s.c.OverseerNodePrioritizer asking the old first in line 73244414069571601-127.0.0.1:34615_solr-n_0000000003 to rejoin election  
   [junit4]   2> 2551449 INFO  (qtp1129992887-38588) [n:127.0.0.1:34615_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=73244414069571601-127.0.0.1:34615_solr-n_0000000003&wt=javabin&version=2} status=0 QTime=7
   [junit4]   2> 2551455 INFO  (OverseerStateUpdate-73244414069571602-127.0.0.1:37849_solr-n_0000000002) [n:127.0.0.1:37849_solr    ] o.a.s.c.Overseer Quit command received {
   [junit4]   2>   "operation":"quit",
   [junit4]   2>   "id":"73244414069571602-127.0.0.1:37849_solr-n_0000000002"} 127.0.0.1:37849_solr
   [junit4]   2> 2551456 INFO  (OverseerStateUpdate-73244414069571602-127.0.0.1:37849_solr-n_0000000002) [n:127.0.0.1:37849_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37849_solr
   [junit4]   2> 2551458 WARN  (OverseerExitThread) [    ] o.a.s.c.Overseer I (id=73244414069571602-127.0.0.1:37849_solr-n_0000000002) am exiting, but I'm still the leader
   [junit4]   2> 2551467 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571602-127.0.0.1:37849_solr-n_0000000002) closing
   [junit4]   2> 2551467 INFO  (OverseerAutoScalingTriggerThread-73244414069571602-127.0.0.1:37849_solr-n_0000000002) [    ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 2551469 INFO  (zkCallback-14864-thread-2) [    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44741_solr
   [junit4]   2> 2551471 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571602-127.0.0.1:37849_solr-n_0000000002) closing
   [junit4]   2> 2551471 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571602-127.0.0.1:37849_solr-n_0000000002) closing
   [junit4]   2> 2551471 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571602-127.0.0.1:37849_solr-n_0000000002) closing
   [junit4]   2> 2551473 INFO  (zkCallback-14864-thread-2) [n:127.0.0.1:44741_solr    ] o.a.s.c.Overseer Overseer (id=73244414069571608-127.0.0.1:44741_solr-n_0000000003) starting
   [junit4]   2> 2551484 INFO  (OverseerStateUpdate-73244414069571608-127.0.0.1:44741_solr-n_0000000003) [n:127.0.0.1:44741_solr    ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:44741_solr
   [junit4]   2> 2551504 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest ### Adding last overseer designate 127.0.0.1:34615_solr
   [junit4]   2> 2551505 INFO  (qtp1129992887-38590) [n:127.0.0.1:34615_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:34615_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551509 INFO  (OverseerThreadFactory-10757-thread-1-processing-n:127.0.0.1:44741_solr) [n:127.0.0.1:44741_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to 127.0.0.1:34615_solr
   [junit4]   2> 2551511 INFO  (qtp1129992887-38590) [n:127.0.0.1:34615_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:34615_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=5
   [junit4]   2> 2551512 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:44741_solr
   [junit4]   2> 2551512 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest Election queue: 
   [junit4]   2> 2551512 INFO  (qtp1129992887-38591) [n:127.0.0.1:34615_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:44741_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551515 INFO  (OverseerCollectionConfigSetProcessor-73244414069571608-127.0.0.1:44741_solr-n_0000000003) [n:127.0.0.1:44741_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000016 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 2551515 INFO  (OverseerThreadFactory-10757-thread-2-processing-n:127.0.0.1:44741_solr) [n:127.0.0.1:44741_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:44741_solr
   [junit4]   2> 2551517 INFO  (qtp1129992887-38591) [n:127.0.0.1:34615_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:44741_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=4
   [junit4]   2> 2551517 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest Expecting node: 127.0.0.1:34615_solr
   [junit4]   2> 2551518 INFO  (Thread-7670) [    ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 73244414069571608-127.0.0.1:44741_solr-n_0000000003 overseer designates are [127.0.0.1:45582_solr, 127.0.0.1:34615_solr]
   [junit4]   2> 2551518 INFO  (Thread-7670) [    ] o.a.s.c.OverseerNodePrioritizer sorted nodes [73244414069571608-127.0.0.1:44741_solr-n_0000000003, 73244414069571597-127.0.0.1:38598_solr-n_0000000007, 73244414069571594-127.0.0.1:43623_solr-n_0000000008, 73244414069571601-127.0.0.1:34615_solr-n_0000000010, 73244414069571602-127.0.0.1:37849_solr-n_0000000011]
   [junit4]   2> 2551518 INFO  (Thread-7670) [    ] o.a.s.c.OverseerNodePrioritizer asking node 73244414069571601-127.0.0.1:34615_solr-n_0000000010 to come join election at head
   [junit4]   2> 2551521 INFO  (zkCallback-14830-thread-2) [    ] o.a.s.c.Overseer Overseer (id=73244414069571602-127.0.0.1:37849_solr-n_0000000002) closing
   [junit4]   2> 2551522 INFO  (qtp1129992887-38588) [n:127.0.0.1:34615_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=73244414069571601-127.0.0.1:34615_solr-n_0000000010&wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 2551523 INFO  (Thread-7670) [    ] o.a.s.c.OverseerNodePrioritizer asking the old first in line 73244414069571597-127.0.0.1:38598_solr-n_0000000007 to rejoin election  
   [junit4]   2> 2551525 INFO  (zkCallback-14790-thread-1) [    ] o.a.s.c.Overseer Overseer (id=73244414069571594-127.0.0.1:43623_solr-n_0000000000) closing
   [junit4]   2> 2551526 INFO  (qtp1205448589-38580) [n:127.0.0.1:38598_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=73244414069571597-127.0.0.1:38598_solr-n_0000000007&wt=javabin&version=2} status=0 QTime=2
   [junit4]   2> 2551528 INFO  (OverseerStateUpdate-73244414069571608-127.0.0.1:44741_solr-n_0000000003) [n:127.0.0.1:44741_solr    ] o.a.s.c.Overseer Quit command received {
   [junit4]   2>   "operation":"quit",
   [junit4]   2>   "id":"73244414069571608-127.0.0.1:44741_solr-n_0000000003"} 127.0.0.1:44741_solr
   [junit4]   2> 2551529 INFO  (OverseerStateUpdate-73244414069571608-127.0.0.1:44741_solr-n_0000000003) [n:127.0.0.1:44741_solr    ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:44741_solr
   [junit4]   2> 2551529 WARN  (OverseerExitThread) [    ] o.a.s.c.Overseer I (id=73244414069571608-127.0.0.1:44741_solr-n_0000000003) am exiting, but I'm still the leader
   [junit4]   2> 2551531 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571608-127.0.0.1:44741_solr-n_0000000003) closing
   [junit4]   2> 2551531 INFO  (OverseerAutoScalingTriggerThread-73244414069571608-127.0.0.1:44741_solr-n_0000000003) [    ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
   [junit4]   2> 2551534 INFO  (zkCallback-14827-thread-1) [    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34615_solr
   [junit4]   2> 2551535 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571608-127.0.0.1:44741_solr-n_0000000003) closing
   [junit4]   2> 2551535 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571608-127.0.0.1:44741_solr-n_0000000003) closing
   [junit4]   2> 2551535 INFO  (OverseerExitThread) [    ] o.a.s.c.Overseer Overseer (id=73244414069571608-127.0.0.1:44741_solr-n_0000000003) closing
   [junit4]   2> 2551535 INFO  (zkCallback-14827-thread-1) [n:127.0.0.1:34615_solr    ] o.a.s.c.Overseer Overseer (id=73244414069571601-127.0.0.1:34615_solr-n_0000000007) starting
   [junit4]   2> 2551539 INFO  (OverseerStateUpdate-73244414069571601-127.0.0.1:34615_solr-n_0000000007) [n:127.0.0.1:34615_solr    ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:34615_solr
   [junit4]   2> 2551619 INFO  (qtp1129992887-38589) [n:127.0.0.1:34615_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:44741_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 2551623 INFO  (OverseerThreadFactory-10761-thread-1-processing-n:127.0.0.1:34615_solr) [n:127.0.0.1:34615_solr    ] o.a.s.c.a.c.OverseerRoleCmd Overseer role added to 127.0.0.1:44741_solr
   [junit4]   2> 2551626 INFO  (qtp1129992887-38589) [n:127.0.0.1:34615_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:44741_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=6
   [junit4]   2> 2551627 INFO  (TEST-OverseerRolesTest.testOverseerRole-seed#[92D96B978233C3FD]) [    ] o.a.s.c.OverseerRolesTest ### Sending QUIT to overseer 127.0.0.1:34615_solr
   [junit4]   2> 2551630 INFO  (OverseerStateUpdate-73244414069571601-127.0.0.1:34615_solr-n_0000000007) [n:127.0.0.1:34615_solr    ] o.a.s.c.Overseer Quit command received {
   [junit4]   2>   "operation":"quit",
   [junit4]   2>   "id":"73244414069571601-127.0.0.1:34615_solr-n_0000000007"} 127.0.0.1:34615_solr
   [junit4]   2> 2551630 INFO  (OverseerStateUpdate-73244414069571601-127.0.0.1:34615_solr-n_0000000007) [

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

:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/lucene/top-level-ivy-settings.xml

resolve:

jar-checksums:
    [mkdir] Created dir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1959603212
     [copy] Copying 239 files to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1959603212
   [delete] Deleting directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-master/checkout/solr/null1959603212

check-working-copy:
[ivy:cachepath] :: resolving dependencies :: #;working@lucene1-us-west
[ivy:cachepath] 	confs: [default]
[ivy:cachepath] 	found org.eclipse.jgit#org.eclipse.jgit;5.3.0.201903130848-r in public
[ivy:cachepath] 	found com.jcraft#jsch;0.1.54 in public
[ivy:cachepath] 	found com.jcraft#jzlib;1.1.1 in public
[ivy:cachepath] 	found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] 	found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] 	found org.bouncycastle#bcpg-jdk15on;1.60 in public
[ivy:cachepath] 	found org.bouncycastle#bcprov-jdk15on;1.60 in public
[ivy:cachepath] 	found org.bouncycastle#bcpkix-jdk15on;1.60 in public
[ivy:cachepath] 	found org.slf4j#slf4j-nop;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 61ms :: artifacts dl 6ms
	---------------------------------------------------------------------
	|                  |            modules            ||   artifacts   |
	|       conf       | number| search|dwnlded|evicted|| number|dwnlded|
	---------------------------------------------------------------------
	|      default     |   9   |   0   |   0   |   0   ||   9   |   0   |
	---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] Checking working copy status...

-jenkins-base:

BUILD SUCCESSFUL
Total time: 435 minutes 59 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
	at hudson.FilePath$ValidateAntFileMask.hasMatch(FilePath.java:2847)
	at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2726)
	at hudson.FilePath$ValidateAntFileMask.invoke(FilePath.java:2707)
	at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3086)
Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene
		at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
		at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
		at hudson.remoting.Channel.call(Channel.java:955)
		at hudson.FilePath.act(FilePath.java:1072)
		at hudson.FilePath.act(FilePath.java:1061)
		at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
		at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
		at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
		at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
		at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
		at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
		at hudson.model.Build$BuildExecution.post2(Build.java:186)
		at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
		at hudson.model.Run.execute(Run.java:1835)
		at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
		at hudson.model.ResourceController.execute(ResourceController.java:97)
		at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
	at hudson.FilePath$FileCallableWrapper.call(FilePath.java:3088)
	at hudson.remoting.UserRequest.perform(UserRequest.java:212)
	at hudson.remoting.UserRequest.perform(UserRequest.java:54)
	at hudson.remoting.Request$2.run(Request.java:369)
	at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
	at hudson.FilePath.act(FilePath.java:1074)
	at hudson.FilePath.act(FilePath.java:1061)
	at hudson.FilePath.validateAntFileMask(FilePath.java:2705)
	at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
	at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
	at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
	at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
	at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
	at hudson.model.Build$BuildExecution.post2(Build.java:186)
	at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
	at hudson.model.Run.execute(Run.java:1835)
	at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
	at hudson.model.ResourceController.execute(ResourceController.java:97)
	at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)

Mime
View raw message