Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6638/
Java: 32bit/jdk1.8.0_131 -server -XX:+UseParallelGC
3 tests failed.
FAILED: org.apache.lucene.analysis.ja.TestFactories.test
Error Message:
chars length is 0
Stack Trace:
java.lang.NumberFormatException: chars length is 0
at
__randomizedtesting.SeedInfo.seed([E0CAA3B6B0BB470F:689E9C6C1E472AF7]:0)
at org.apache.lucene.util.ArrayUtil.parseInt(ArrayUtil.java:73)
at org.apache.lucene.util.ArrayUtil.parseInt(ArrayUtil.java:51)
at
org.apache.lucene.analysis.miscellaneous.DelimitedTermFrequencyTokenFilter.incrementToken(DelimitedTermFrequencyTokenFilter.java:67)
at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:731)
at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:642)
at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:540)
at
org.apache.lucene.analysis.ja.TestFactories.doTestTokenFilter(TestFactories.java:102)
at
org.apache.lucene.analysis.ja.TestFactories.test(TestFactories.java:55)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.rule.RulesTest.doIntegrationTest
Error Message:
Error from server at http://127.0.0.1:55500/solr: delete the collection unknown
case
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error
from server at http://127.0.0.1:55500/solr: delete the collection unknown case
at
__randomizedtesting.SeedInfo.seed([5AA0C772C2D36C14:BF9380F3DEA79E16]:0)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at
org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:237)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:459)
at
org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:389)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1130)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:871)
at
org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160)
at
org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:177)
at
org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:442)
at
org.apache.solr.cloud.rule.RulesTest.removeCollections(RulesTest.java:63)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:965)
at
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
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.lang.Thread.run(Thread.java:748)
FAILED:
org.apache.solr.cloud.rule.RulesTest.testHostFragmentRuleThrowsExceptionWhenIpDoesNotMatch
Error Message:
Expected: (exception with message a string containing "ip_1" and an instance
of org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException)
got: <org.junit.runners.model.MultipleFailureException: There were 2 errors:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException(Error from
server at http://127.0.0.1:55500/solr: Could not identify nodes matching the
rules [{"ip_2":"0"}, {"ip_1":"19999"}] tag values{ "127.0.0.1:55500_solr":{
"ip_1":"1", "ip_2":"0"}, "127.0.0.1:55502_solr":{ "ip_1":"1",
"ip_2":"0"}, "127.0.0.1:55510_solr":{ "ip_1":"1", "ip_2":"0"},
"127.0.0.1:55514_solr":{ "ip_1":"1", "ip_2":"0"},
"127.0.0.1:55511_solr":{ "ip_1":"1", "ip_2":"0"}})
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException(Error from
server at http://127.0.0.1:55500/solr: Could not find collection : rulesColl)>
Stack Trace:
java.lang.AssertionError:
Expected: (exception with message a string containing "ip_1" and an instance of
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException)
got: <org.junit.runners.model.MultipleFailureException: There were 2
errors:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException(Error
from server at http://127.0.0.1:55500/solr: Could not identify nodes matching
the rules [{"ip_2":"0"}, {"ip_1":"19999"}]
tag values{
"127.0.0.1:55500_solr":{
"ip_1":"1",
"ip_2":"0"},
"127.0.0.1:55502_solr":{
"ip_1":"1",
"ip_2":"0"},
"127.0.0.1:55510_solr":{
"ip_1":"1",
"ip_2":"0"},
"127.0.0.1:55514_solr":{
"ip_1":"1",
"ip_2":"0"},
"127.0.0.1:55511_solr":{
"ip_1":"1",
"ip_2":"0"}})
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException(Error
from server at http://127.0.0.1:55500/solr: Could not find collection :
rulesColl)>
at
__randomizedtesting.SeedInfo.seed([5AA0C772C2D36C14:7E56D9066F06F05C]:0)
at org.junit.Assert.assertThat(Assert.java:780)
at org.junit.Assert.assertThat(Assert.java:738)
at
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:114)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
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.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 3528 lines...]
[junit4] Suite: org.apache.lucene.analysis.ja.TestFactories
[junit4] 2> TEST FAIL: useCharFilter=false text=')yr|
\ufe56\ufe65\ufe50\ufe63\ufe5a pnty '
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestFactories
-Dtests.method=test -Dtests.seed=E0CAA3B6B0BB470F -Dtests.slow=true
-Dtests.locale=fi-FI -Dtests.timezone=America/Aruba -Dtests.asserts=true
-Dtests.file.encoding=UTF-8
[junit4] ERROR 9.23s J1 | TestFactories.test <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: chars length is 0
[junit4] > at
__randomizedtesting.SeedInfo.seed([E0CAA3B6B0BB470F:689E9C6C1E472AF7]:0)
[junit4] > at
org.apache.lucene.util.ArrayUtil.parseInt(ArrayUtil.java:73)
[junit4] > at
org.apache.lucene.util.ArrayUtil.parseInt(ArrayUtil.java:51)
[junit4] > at
org.apache.lucene.analysis.miscellaneous.DelimitedTermFrequencyTokenFilter.incrementToken(DelimitedTermFrequencyTokenFilter.java:67)
[junit4] > at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkAnalysisConsistency(BaseTokenStreamTestCase.java:731)
[junit4] > at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:642)
[junit4] > at
org.apache.lucene.analysis.BaseTokenStreamTestCase.checkRandomData(BaseTokenStreamTestCase.java:540)
[junit4] > at
org.apache.lucene.analysis.ja.TestFactories.doTestTokenFilter(TestFactories.java:102)
[junit4] > at
org.apache.lucene.analysis.ja.TestFactories.test(TestFactories.java:55)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70):
{dummy=Lucene50(blocksize=128)}, docValues:{}, maxPointsInLeafNode=593,
maxMBSortInHeap=7.910211527921591, sim=RandomSimilarity(queryNorm=true): {},
locale=fi-FI, timezone=America/Aruba
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_131
(32-bit)/cpus=3,threads=1,free=190477280,total=226230272
[junit4] 2> NOTE: All tests run in this JVM: [TestSearchMode,
UserDictionaryTest, TestExtendedMode,
TestJapaneseIterationMarkCharFilterFactory, TestJapaneseNumberFilterFactory,
TestJapaneseAnalyzer, TestJapaneseKatakanaStemFilter, TestTokenInfoDictionary,
TestJapanesePartOfSpeechStopFilterFactory, TestJapaneseBaseFormFilterFactory,
TestJapaneseReadingFormFilterFactory, TestToStringUtil,
TestJapaneseKatakanaStemFilterFactory, TestCSVUtil, TestFactories]
[junit4] Completed [21/21 (1!)] on J1 in 9.28s, 1 test, 1 error <<< FAILURES!
[...truncated 7696 lines...]
[junit4] Suite: org.apache.solr.cloud.rule.RulesTest
[junit4] 2> Creating dataDir:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\init-core-data-001
[junit4] 2> 152141 WARN (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 152141 INFO (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 152147 INFO (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via:
@org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 152149 INFO (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 5 servers in
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001
[junit4] 2> 152149 INFO (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 152149 INFO (Thread-258) [ ] o.a.s.c.ZkTestServer client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 152150 INFO (Thread-258) [ ] o.a.s.c.ZkTestServer Starting
server
[junit4] 2> 152154 ERROR (Thread-258) [ ] o.a.z.s.ZooKeeperServer
ZKShutdownHandler is not registered, so ZooKeeper server won't take any action
on ERROR or SHUTDOWN server state changes
[junit4] 2> 152249 INFO (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.s.c.ZkTestServer start zk server on port:55496
[junit4] 2> 152262 INFO (jetty-launcher-143-thread-1) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 152266 INFO (jetty-launcher-143-thread-5) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 152267 INFO (jetty-launcher-143-thread-5) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@132f62c{/solr,null,AVAILABLE}
[junit4] 2> 152267 INFO (jetty-launcher-143-thread-1) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@7c70b9{/solr,null,AVAILABLE}
[junit4] 2> 152269 INFO (jetty-launcher-143-thread-1) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@5671c3{HTTP/1.1,[http/1.1]}{127.0.0.1:55502}
[junit4] 2> 152269 INFO (jetty-launcher-143-thread-1) [ ]
o.e.j.s.Server Started @156935ms
[junit4] 2> 152269 INFO (jetty-launcher-143-thread-1) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55502}
[junit4] 2> 152269 INFO (jetty-launcher-143-thread-5) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@197de88{HTTP/1.1,[http/1.1]}{127.0.0.1:55500}
[junit4] 2> 152269 INFO (jetty-launcher-143-thread-5) [ ]
o.e.j.s.Server Started @156935ms
[junit4] 2> 152269 INFO (jetty-launcher-143-thread-5) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55500}
[junit4] 2> 152269 ERROR (jetty-launcher-143-thread-5) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 152270 INFO (jetty-launcher-143-thread-5) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 152270 INFO (jetty-launcher-143-thread-5) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 152270 INFO (jetty-launcher-143-thread-5) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 152270 INFO (jetty-launcher-143-thread-5) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-11T05:30:43.651Z
[junit4] 2> 152270 ERROR (jetty-launcher-143-thread-1) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 152270 INFO (jetty-launcher-143-thread-1) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 152270 INFO (jetty-launcher-143-thread-1) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 152270 INFO (jetty-launcher-143-thread-1) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 152270 INFO (jetty-launcher-143-thread-1) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-11T05:30:43.651Z
[junit4] 2> 152277 INFO (jetty-launcher-143-thread-2) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 152277 INFO (jetty-launcher-143-thread-3) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 152279 INFO (jetty-launcher-143-thread-4) [ ]
o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 152282 INFO (jetty-launcher-143-thread-3) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@a700b4{/solr,null,AVAILABLE}
[junit4] 2> 152282 INFO (jetty-launcher-143-thread-2) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@3544ad{/solr,null,AVAILABLE}
[junit4] 2> 152283 INFO (jetty-launcher-143-thread-4) [ ]
o.e.j.s.h.ContextHandler Started
o.e.j.s.ServletContextHandler@117c064{/solr,null,AVAILABLE}
[junit4] 2> 152283 INFO (jetty-launcher-143-thread-3) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@142928b{HTTP/1.1,[http/1.1]}{127.0.0.1:55510}
[junit4] 2> 152283 INFO (jetty-launcher-143-thread-2) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@13e1d8c{HTTP/1.1,[http/1.1]}{127.0.0.1:55511}
[junit4] 2> 152284 INFO (jetty-launcher-143-thread-4) [ ]
o.e.j.s.AbstractConnector Started
ServerConnector@12a3b87{HTTP/1.1,[http/1.1]}{127.0.0.1:55514}
[junit4] 2> 152284 INFO (jetty-launcher-143-thread-4) [ ]
o.e.j.s.Server Started @156949ms
[junit4] 2> 152284 INFO (jetty-launcher-143-thread-4) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55514}
[junit4] 2> 152284 ERROR (jetty-launcher-143-thread-4) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 152284 INFO (jetty-launcher-143-thread-4) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 152284 INFO (jetty-launcher-143-thread-4) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 152284 INFO (jetty-launcher-143-thread-4) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 152284 INFO (jetty-launcher-143-thread-4) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-11T05:30:43.665Z
[junit4] 2> 152285 INFO (jetty-launcher-143-thread-2) [ ]
o.e.j.s.Server Started @156949ms
[junit4] 2> 152285 INFO (jetty-launcher-143-thread-2) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55511}
[junit4] 2> 152287 ERROR (jetty-launcher-143-thread-2) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 152287 INFO (jetty-launcher-143-thread-2) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 152287 INFO (jetty-launcher-143-thread-2) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 152287 INFO (jetty-launcher-143-thread-2) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 152287 INFO (jetty-launcher-143-thread-2) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-11T05:30:43.668Z
[junit4] 2> 152288 INFO (jetty-launcher-143-thread-3) [ ]
o.e.j.s.Server Started @156949ms
[junit4] 2> 152288 INFO (jetty-launcher-143-thread-3) [ ]
o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr,
hostPort=55510}
[junit4] 2> 152289 ERROR (jetty-launcher-143-thread-3) [ ]
o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be
missing or incomplete.
[junit4] 2> 152289 INFO (jetty-launcher-143-thread-3) [ ]
o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version
7.0.0
[junit4] 2> 152289 INFO (jetty-launcher-143-thread-3) [ ]
o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 152289 INFO (jetty-launcher-143-thread-3) [ ]
o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 152289 INFO (jetty-launcher-143-thread-3) [ ]
o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time:
2017-06-11T05:30:43.670Z
[junit4] 2> 152313 INFO (jetty-launcher-143-thread-3) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 152313 INFO (jetty-launcher-143-thread-5) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 152314 INFO (jetty-launcher-143-thread-1) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 152314 INFO (jetty-launcher-143-thread-2) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 152316 INFO (jetty-launcher-143-thread-4) [ ]
o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 152331 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c95a209cf0004, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 152346 INFO (jetty-launcher-143-thread-1) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55496/solr
[junit4] 2> 152359 INFO (jetty-launcher-143-thread-3) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55496/solr
[junit4] 2> 152366 INFO (jetty-launcher-143-thread-2) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55496/solr
[junit4] 2> 152369 INFO (jetty-launcher-143-thread-4) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55496/solr
[junit4] 2> 152378 INFO (jetty-launcher-143-thread-5) [ ]
o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:55496/solr
[junit4] 2> 152381 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c95a209cf0006, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 152383 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c95a209cf0007, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 152447 INFO (jetty-launcher-143-thread-3)
[n:127.0.0.1:55510_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 152448 INFO (jetty-launcher-143-thread-1)
[n:127.0.0.1:55502_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 152448 INFO (jetty-launcher-143-thread-2)
[n:127.0.0.1:55511_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 152448 INFO (jetty-launcher-143-thread-4)
[n:127.0.0.1:55514_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 152451 INFO (jetty-launcher-143-thread-3)
[n:127.0.0.1:55510_solr ] o.a.s.c.OverseerElectionContext I am going to be
the leader 127.0.0.1:55510_solr
[junit4] 2> 152453 INFO (jetty-launcher-143-thread-1)
[n:127.0.0.1:55502_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55502_solr
[junit4] 2> 152455 INFO (jetty-launcher-143-thread-3)
[n:127.0.0.1:55510_solr ] o.a.s.c.Overseer Overseer
(id=98117815077109772-127.0.0.1:55510_solr-n_0000000000) starting
[junit4] 2> 152456 INFO (jetty-launcher-143-thread-2)
[n:127.0.0.1:55511_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55511_solr
[junit4] 2> 152456 INFO (jetty-launcher-143-thread-4)
[n:127.0.0.1:55514_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55514_solr
[junit4] 2> 152466 INFO
(zkCallback-166-thread-1-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 152466 INFO
(zkCallback-167-thread-1-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 152468 INFO
(zkCallback-168-thread-1-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (1)
[junit4] 2> 152598 INFO (jetty-launcher-143-thread-2)
[n:127.0.0.1:55511_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55511.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152601 INFO (jetty-launcher-143-thread-2)
[n:127.0.0.1:55511_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55511.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152601 INFO (jetty-launcher-143-thread-2)
[n:127.0.0.1:55511_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55511.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152603 INFO (jetty-launcher-143-thread-2)
[n:127.0.0.1:55511_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node5\.
[junit4] 2> 152603 INFO
(zkCallback-169-thread-2-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 152603 INFO
(zkCallback-168-thread-2-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (3)
[junit4] 2> 152603 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (3)
[junit4] 2> 152603 INFO
(zkCallback-167-thread-2-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (1) -> (3)
[junit4] 2> 152654 INFO (jetty-launcher-143-thread-5)
[n:127.0.0.1:55500_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (0) -> (3)
[junit4] 2> 152661 INFO (jetty-launcher-143-thread-5)
[n:127.0.0.1:55500_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 152663 INFO (jetty-launcher-143-thread-3)
[n:127.0.0.1:55510_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55510_solr
[junit4] 2> 152666 INFO (jetty-launcher-143-thread-5)
[n:127.0.0.1:55500_solr ] o.a.s.c.ZkController Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:55500_solr
[junit4] 2> 152674 INFO
(zkCallback-167-thread-2-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 152677 INFO
(zkCallback-170-thread-1-processing-n:127.0.0.1:55500_solr)
[n:127.0.0.1:55500_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 152678 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 152679 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 152679 INFO
(zkCallback-168-thread-2-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (3) -> (4)
[junit4] 2> 152711 INFO
(OverseerStateUpdate-98117815077109772-127.0.0.1:55510_solr-n_0000000000)
[n:127.0.0.1:55510_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 152712 INFO
(zkCallback-170-thread-1-processing-n:127.0.0.1:55500_solr)
[n:127.0.0.1:55500_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 152712 INFO
(zkCallback-168-thread-2-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 152712 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 152712 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (5)
[junit4] 2> 152796 INFO (jetty-launcher-143-thread-4)
[n:127.0.0.1:55514_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55514.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152799 INFO (jetty-launcher-143-thread-4)
[n:127.0.0.1:55514_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55514.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152800 INFO (jetty-launcher-143-thread-4)
[n:127.0.0.1:55514_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55514.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152801 INFO (jetty-launcher-143-thread-4)
[n:127.0.0.1:55514_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node3\.
[junit4] 2> 152805 INFO (jetty-launcher-143-thread-1)
[n:127.0.0.1:55502_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55502.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152808 INFO (jetty-launcher-143-thread-1)
[n:127.0.0.1:55502_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55502.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152808 INFO (jetty-launcher-143-thread-1)
[n:127.0.0.1:55502_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55502.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152810 INFO (jetty-launcher-143-thread-1)
[n:127.0.0.1:55502_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node1\.
[junit4] 2> 152813 INFO (jetty-launcher-143-thread-3)
[n:127.0.0.1:55510_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55510.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152817 INFO (jetty-launcher-143-thread-3)
[n:127.0.0.1:55510_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55510.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152818 INFO (jetty-launcher-143-thread-3)
[n:127.0.0.1:55510_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55510.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152819 INFO (jetty-launcher-143-thread-3)
[n:127.0.0.1:55510_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node4\.
[junit4] 2> 152949 INFO (jetty-launcher-143-thread-5)
[n:127.0.0.1:55500_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55500.solr.node' (registry 'solr.node') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152951 INFO (jetty-launcher-143-thread-5)
[n:127.0.0.1:55500_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55500.solr.jvm' (registry 'solr.jvm') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152952 INFO (jetty-launcher-143-thread-5)
[n:127.0.0.1:55500_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55500.solr.jetty' (registry 'solr.jetty') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 152953 INFO (jetty-launcher-143-thread-5)
[n:127.0.0.1:55500_solr ] o.a.s.c.CorePropertiesLocator Found 0 core
definitions underneath
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node2\.
[junit4] 2> 152998 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c95a209cf0010, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 153008 INFO (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 153009 INFO (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:55496/solr
ready
[junit4] 2> 153054 INFO
(TEST-RulesTest.testModifyColl-seed#[5AA0C772C2D36C14]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testModifyColl
[junit4] 2> 153066 INFO (qtp7482241-1201) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
replicationFactor=2&collection.configName=conf&snitch=class:ImplicitSnitch&name=modifyColl&nrtReplicas=2&action=CREATE&numShards=1&rule=cores:<4&rule=node:*,replica:1&rule=freedisk:>1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 153098 INFO
(OverseerThreadFactory-529-thread-1-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.CreateCollectionCmd Create collection
modifyColl
[junit4] 2> 153108 INFO (qtp9004849-1230) [n:127.0.0.1:55514_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 153109 INFO (qtp9004849-1230) [n:127.0.0.1:55514_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 153109 INFO (qtp9004849-1230) [n:127.0.0.1:55514_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=2
[junit4] 2> 153114 INFO (qtp15820438-1193) [n:127.0.0.1:55502_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 153116 INFO (qtp15820438-1193) [n:127.0.0.1:55502_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 153116 INFO (qtp15820438-1193) [n:127.0.0.1:55502_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=2
[junit4] 2> 153120 INFO (qtp11986047-1222) [n:127.0.0.1:55510_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 153121 INFO (qtp11986047-1222) [n:127.0.0.1:55510_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 153122 INFO (qtp11986047-1222) [n:127.0.0.1:55510_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=2
[junit4] 2> 153123 INFO (qtp7482241-1199) [n:127.0.0.1:55500_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 153124 INFO (qtp7482241-1199) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 153124 INFO (qtp7482241-1199) [n:127.0.0.1:55500_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=1
[junit4] 2> 153127 INFO (qtp9729593-1237) [n:127.0.0.1:55511_solr ]
o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647
transient cores
[junit4] 2> 153130 INFO (qtp9729593-1237) [n:127.0.0.1:55511_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 153130 INFO (qtp9729593-1237) [n:127.0.0.1:55511_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=1
[junit4] 2> 153250 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=modifyColl_shard1_replica_n1&action=CREATE&numShards=1&collection=modifyColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 153253 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=modifyColl_shard1_replica_n2&action=CREATE&numShards=1&collection=modifyColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 153361 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 153361 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 154294 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 154294 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 154303 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.s.IndexSchema
[modifyColl_shard1_replica_n2] Schema name=minimal
[junit4] 2> 154305 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.s.IndexSchema
[modifyColl_shard1_replica_n1] Schema name=minimal
[junit4] 2> 154309 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 154309 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.s.IndexSchema
Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 154309 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.c.CoreContainer
Creating SolrCore 'modifyColl_shard1_replica_n2' using configuration from
collection modifyColl, trusted=true
[junit4] 2> 154309 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.c.CoreContainer
Creating SolrCore 'modifyColl_shard1_replica_n1' using configuration from
collection modifyColl, trusted=true
[junit4] 2> 154310 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55502.solr.core.modifyColl.shard1.replica_n2' (registry
'solr.core.modifyColl.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 154310 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.m.r.SolrJmxReporter JMX monitoring for
'solr_55514.solr.core.modifyColl.shard1.replica_n1' (registry
'solr.core.modifyColl.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 154310 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 154310 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 154310 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.c.SolrCore
[[modifyColl_shard1_replica_n2] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node1\modifyColl_shard1_replica_n2],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node1\.\modifyColl_shard1_replica_n2\data\]
[junit4] 2> 154310 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.c.SolrCore
[[modifyColl_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node3\modifyColl_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node3\.\modifyColl_shard1_replica_n1\data\]
[junit4] 2> 154425 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 154425 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 154425 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 154425 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 154427 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 154427 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 154427 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.u.CommitTracker
Hard AutoCommit: disabled
[junit4] 2> 154427 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.u.CommitTracker
Soft AutoCommit: disabled
[junit4] 2> 154429 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@1140c3c[modifyColl_shard1_replica_n1] main]
[junit4] 2> 154430 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 154430 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2]
o.a.s.s.SolrIndexSearcher Opening
[Searcher@c080cf[modifyColl_shard1_replica_n2] main]
[junit4] 2> 154430 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 154431 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 154431 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1569885043621363712
[junit4] 2> 154431 INFO
(searcherExecutor-538-thread-1-processing-n:127.0.0.1:55514_solr
x:modifyColl_shard1_replica_n1 s:shard1 c:modifyColl) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.c.SolrCore
[modifyColl_shard1_replica_n1] Registered new searcher
Searcher@1140c3c[modifyColl_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 154432 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 154432 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 154433 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2]
o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 154434 INFO
(searcherExecutor-537-thread-1-processing-n:127.0.0.1:55502_solr
x:modifyColl_shard1_replica_n2 s:shard1 c:modifyColl) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.c.SolrCore
[modifyColl_shard1_replica_n2] Registered new searcher
Searcher@c080cf[modifyColl_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 154434 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1569885043624509440
[junit4] 2> 154436 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 154539 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 154539 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 154938 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 154938 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 154938 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.c.SyncStrategy
Sync replicas to http://127.0.0.1:55514/solr/modifyColl_shard1_replica_n1/
[junit4] 2> 154939 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.u.PeerSync
PeerSync: core=modifyColl_shard1_replica_n1 url=http://127.0.0.1:55514/solr
START replicas=[http://127.0.0.1:55502/solr/modifyColl_shard1_replica_n2/]
nUpdates=100
[junit4] 2> 154939 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.u.PeerSync
PeerSync: core=modifyColl_shard1_replica_n1 url=http://127.0.0.1:55514/solr
DONE. We have no versions. sync failed.
[junit4] 2> 154944 INFO (qtp15820438-1190) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 r:core_node2 x:modifyColl_shard1_replica_n2]
o.a.s.c.S.Request [modifyColl_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=1
[junit4] 2> 154945 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.c.SyncStrategy
Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 154945 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 154945 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 154961 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:55514/solr/modifyColl_shard1_replica_n1/ shard1
[junit4] 2> 155069 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 155069 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 155165 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.c.ZkController I
am the leader, no recovery necessary
[junit4] 2> 155211 INFO (qtp9004849-1229) [n:127.0.0.1:55514_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n1] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=modifyColl_shard1_replica_n1&action=CREATE&numShards=1&collection=modifyColl&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1961
[junit4] 2> 155310 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 155311 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 155450 INFO (qtp15820438-1192) [n:127.0.0.1:55502_solr
c:modifyColl s:shard1 x:modifyColl_shard1_replica_n2] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=modifyColl_shard1_replica_n2&action=CREATE&numShards=1&collection=modifyColl&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2197
[junit4] 2> 155458 INFO (qtp7482241-1201) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 155819 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 155819 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 156520 INFO (qtp7482241-1201) [n:127.0.0.1:55500_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={replicationFactor=2&collection.configName=conf&snitch=class:ImplicitSnitch&name=modifyColl&nrtReplicas=2&action=CREATE&numShards=1&rule=cores:<4&rule=node:*,replica:1&rule=freedisk:>1&wt=javabin&version=2}
status=0 QTime=3454
[junit4] 2> 156521 INFO (qtp7482241-1201) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :modifycollection with
params
autoAddReplicas=true&action=MODIFYCOLLECTION&rule=cores:<5&rule=node:*,replica:1&rule=freedisk:>5&collection=modifyColl&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 156525 INFO
(OverseerCollectionConfigSetProcessor-98117815077109772-127.0.0.1:55510_solr-n_0000000000)
[n:127.0.0.1:55510_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> 156930 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 156930 INFO
(zkCallback-169-thread-1-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/modifyColl/state.json] for collection [modifyColl] has
occurred - updating... (live nodes size: [5])
[junit4] 2> 157151 INFO (qtp7482241-1201) [n:127.0.0.1:55500_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={autoAddReplicas=true&action=MODIFYCOLLECTION&rule=cores:<5&rule=node:*,replica:1&rule=freedisk:>5&collection=modifyColl&wt=javabin&version=2}
status=0 QTime=630
[junit4] 2> 157152 INFO
(TEST-RulesTest.testModifyColl-seed#[5AA0C772C2D36C14]) [ ]
o.a.s.c.r.RulesTest version_of_coll 6
[junit4] 2> 157155 INFO
(TEST-RulesTest.testModifyColl-seed#[5AA0C772C2D36C14]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 157155 INFO
(OverseerCollectionConfigSetProcessor-98117815077109772-127.0.0.1:55510_solr-n_0000000000)
[n:127.0.0.1:55510_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> 157160 INFO (qtp7482241-1198) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :delete with params
name=modifyColl&action=DELETE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 157197 INFO
(OverseerThreadFactory-529-thread-3-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.OverseerCollectionMessageHandler Executing
Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 157199 INFO (qtp9004849-1228) [n:127.0.0.1:55514_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.modifyColl.shard1.replica_n1, tag=null
[junit4] 2> 157207 INFO (qtp9004849-1228) [n:127.0.0.1:55514_solr ]
o.a.s.c.SolrCore [modifyColl_shard1_replica_n1] CLOSING SolrCore
org.apache.solr.core.SolrCore@146afad
[junit4] 2> 157243 INFO (qtp15820438-1189) [n:127.0.0.1:55502_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.modifyColl.shard1.replica_n2, tag=null
[junit4] 2> 157247 INFO (qtp15820438-1189) [n:127.0.0.1:55502_solr ]
o.a.s.c.SolrCore [modifyColl_shard1_replica_n2] CLOSING SolrCore
org.apache.solr.core.SolrCore@1e6ed6b
[junit4] 2> 157250 INFO (qtp9004849-1228) [n:127.0.0.1:55514_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.modifyColl.shard1.replica_n1, tag=21409709
[junit4] 2> 157250 INFO (qtp9004849-1228) [n:127.0.0.1:55514_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.modifyColl.shard1.leader, tag=21409709
[junit4] 2> 157256 INFO (qtp15820438-1189) [n:127.0.0.1:55502_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.core.modifyColl.shard1.replica_n2, tag=31911275
[junit4] 2> 157256 INFO (qtp15820438-1189) [n:127.0.0.1:55502_solr ]
o.a.s.m.SolrMetricManager Closing metric reporters for
registry=solr.collection.modifyColl.shard1.leader, tag=31911275
[junit4] 2> 157303 INFO (qtp15820438-1189) [n:127.0.0.1:55502_solr ]
o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader
parent node, won't remove previous leader registration.
[junit4] 2> 157306 INFO (qtp15820438-1189) [n:127.0.0.1:55502_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=modifyColl_shard1_replica_n2&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=63
[junit4] 2> 157307 WARN
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.LeaderElector Our node is no longer in
line to be leader
[junit4] 2> 157309 INFO (qtp9004849-1228) [n:127.0.0.1:55514_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={deleteInstanceDir=true&core=modifyColl_shard1_replica_n1&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2}
status=0 QTime=109
[junit4] 2> 158248 INFO (qtp7482241-1198) [n:127.0.0.1:55500_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={name=modifyColl&action=DELETE&wt=javabin&version=2} status=0 QTime=1088
[junit4] 2> 158249 INFO
(TEST-RulesTest.testModifyColl-seed#[5AA0C772C2D36C14]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testModifyColl
[junit4] 2> 158344 INFO
(TEST-RulesTest.testInvokeApi-seed#[5AA0C772C2D36C14]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting testInvokeApi
[junit4] 2> 158371 INFO (qtp9004849-1227) [n:127.0.0.1:55514_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 158372 INFO (qtp9004849-1227) [n:127.0.0.1:55514_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/invoke
params={cores=1&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=2
[junit4] 2> 158378 INFO
(OverseerCollectionConfigSetProcessor-98117815077109772-127.0.0.1:55510_solr-n_0000000000)
[n:127.0.0.1:55510_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> 158379 INFO
(TEST-RulesTest.testInvokeApi-seed#[5AA0C772C2D36C14]) [ ]
o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 158381 INFO
(TEST-RulesTest.testInvokeApi-seed#[5AA0C772C2D36C14]) [ ]
o.a.s.SolrTestCaseJ4 ###Ending testInvokeApi
[junit4] 2> 158547 INFO
(TEST-RulesTest.doIntegrationTest-seed#[5AA0C772C2D36C14]) [ ]
o.a.s.SolrTestCaseJ4 ###Starting doIntegrationTest
[junit4] 2> 158549 INFO (qtp7482241-1202) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params
shards=shard1&replicationFactor=2&collection.configName=conf&snitch=class:ImplicitSnitch&name=rulesColl&router.name=implicit&nrtReplicas=2&action=CREATE&rule=cores:<4&rule=node:*,replica:<2&rule=freedisk:>1&wt=javabin&version=2
and sendToOCPQueue=true
[junit4] 2> 158753 INFO
(OverseerThreadFactory-529-thread-4-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.CreateCollectionCmd Create collection
rulesColl
[junit4] 2> 158758 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 158758 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=1
[junit4] 2> 158761 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 158761 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=1
[junit4] 2> 158765 INFO (qtp9004849-1227) [n:127.0.0.1:55514_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 158765 INFO (qtp9004849-1227) [n:127.0.0.1:55514_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=1
[junit4] 2> 158771 INFO (qtp7482241-1208) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 158771 INFO (qtp7482241-1208) [n:127.0.0.1:55500_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=1
[junit4] 2> 158775 INFO (qtp11986047-1218) [n:127.0.0.1:55510_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 158775 INFO (qtp11986047-1218) [n:127.0.0.1:55510_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=1
[junit4] 2> 159105 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=rulesColl_shard1_replica_n1&action=CREATE&numShards=1&collection=rulesColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 159116 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr ]
o.a.s.h.a.CoreAdminOperation core create command
qt=/admin/cores&collection.configName=conf&newCollection=true&name=rulesColl_shard1_replica_n2&action=CREATE&numShards=1&collection=rulesColl&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 159233 INFO
(zkCallback-168-thread-2-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 159233 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 159375 INFO
(zkCallback-168-thread-2-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 159375 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 160147 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 160154 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.s.IndexSchema
[rulesColl_shard1_replica_n1] Schema name=minimal
[junit4] 2> 160158 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 160158 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.c.CoreContainer
Creating SolrCore 'rulesColl_shard1_replica_n1' using configuration from
collection rulesColl, trusted=true
[junit4] 2> 160159 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter
JMX monitoring for 'solr_55511.solr.core.rulesColl.shard1.replica_n1' (registry
'solr.core.rulesColl.shard1.replica_n1') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 160159 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 160159 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.c.SolrCore
[[rulesColl_shard1_replica_n1] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node5\rulesColl_shard1_replica_n1],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node5\.\rulesColl_shard1_replica_n1\data\]
[junit4] 2> 160269 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 160269 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 160271 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 160271 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 160290 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.s.SolrIndexSearcher
Opening [Searcher@dc3e02[rulesColl_shard1_replica_n1] main]
[junit4] 2> 160291 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 160292 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 160293 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 160293 INFO
(searcherExecutor-547-thread-1-processing-n:127.0.0.1:55511_solr
x:rulesColl_shard1_replica_n1 s:shard1 c:rulesColl) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.c.SolrCore
[rulesColl_shard1_replica_n1] Registered new searcher
Searcher@dc3e02[rulesColl_shard1_replica_n1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 160294 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1569885049769164800
[junit4] 2> 160297 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.c.SolrConfig Using
Lucene MatchVersion: 7.0.0
[junit4] 2> 160300 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for
shard shard1: total=2 found=1 timeoutin=9999ms
[junit4] 2> 160303 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.s.IndexSchema
[rulesColl_shard1_replica_n2] Schema name=minimal
[junit4] 2> 160308 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.s.IndexSchema Loaded
schema minimal/1.1 with uniqueid field id
[junit4] 2> 160308 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.c.CoreContainer
Creating SolrCore 'rulesColl_shard1_replica_n2' using configuration from
collection rulesColl, trusted=true
[junit4] 2> 160308 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter
JMX monitoring for 'solr_55502.solr.core.rulesColl.shard1.replica_n2' (registry
'solr.core.rulesColl.shard1.replica_n2') enabled at server:
com.sun.jmx.mbeanserver.JmxMBeanServer@15589f2
[junit4] 2> 160308 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.c.SolrCore
solr.RecoveryStrategy.Builder
[junit4] 2> 160308 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.c.SolrCore
[[rulesColl_shard1_replica_n2] ] Opening new SolrCore at
[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node1\rulesColl_shard1_replica_n2],
dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001\tempDir-001\node1\.\rulesColl_shard1_replica_n2\data\]
[junit4] 2> 160397 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.u.UpdateHandler
Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 160397 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.u.UpdateLog
Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 160399 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.u.CommitTracker Hard
AutoCommit: disabled
[junit4] 2> 160399 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.u.CommitTracker Soft
AutoCommit: disabled
[junit4] 2> 160401 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.s.SolrIndexSearcher
Opening [Searcher@bcde9e[rulesColl_shard1_replica_n2] main]
[junit4] 2> 160402 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase:
/configs/conf
[junit4] 2> 160403 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2]
o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 160403 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.h.ReplicationHandler
Commits will be reserved for 10000
[junit4] 2> 160404 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.u.UpdateLog Could
not find max version in index or recent updates, using new clock
1569885049884508160
[junit4] 2> 160404 INFO
(searcherExecutor-552-thread-1-processing-n:127.0.0.1:55502_solr
x:rulesColl_shard1_replica_n2 s:shard1 c:rulesColl) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.c.SolrCore
[rulesColl_shard1_replica_n2] Registered new searcher
Searcher@bcde9e[rulesColl_shard1_replica_n2]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 160406 INFO
(zkCallback-168-thread-2-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 160406 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 160800 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 160800 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 160800 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.c.SyncStrategy Sync
replicas to http://127.0.0.1:55511/solr/rulesColl_shard1_replica_n1/
[junit4] 2> 160800 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.u.PeerSync PeerSync:
core=rulesColl_shard1_replica_n1 url=http://127.0.0.1:55511/solr START
replicas=[http://127.0.0.1:55502/solr/rulesColl_shard1_replica_n2/] nUpdates=100
[junit4] 2> 160801 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.u.PeerSync PeerSync:
core=rulesColl_shard1_replica_n1 url=http://127.0.0.1:55511/solr DONE. We have
no versions. sync failed.
[junit4] 2> 160803 INFO (qtp15820438-1196) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 r:core_node2 x:rulesColl_shard1_replica_n2]
o.a.s.c.S.Request [rulesColl_shard1_replica_n2] webapp=/solr path=/get
params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2}
status=0 QTime=0
[junit4] 2> 160803 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.c.SyncStrategy
Leader's attempt to sync with shard failed, moving to the next candidate
[junit4] 2> 160803 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we
can't sync in that case - we were active before, so become leader anyway
[junit4] 2> 160803 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext Found all replicas participating in
election, clear LIR
[junit4] 2> 160809 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1]
o.a.s.c.ShardLeaderElectionContext I am the new leader:
http://127.0.0.1:55511/solr/rulesColl_shard1_replica_n1/ shard1
[junit4] 2> 160913 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 160913 INFO
(zkCallback-168-thread-2-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 160962 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.c.ZkController I am
the leader, no recovery necessary
[junit4] 2> 160966 INFO (qtp9729593-1236) [n:127.0.0.1:55511_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n1] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=rulesColl_shard1_replica_n1&action=CREATE&numShards=1&collection=rulesColl&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=1860
[junit4] 2> 161077 INFO
(zkCallback-168-thread-2-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 161085 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 161415 INFO (qtp15820438-1210) [n:127.0.0.1:55502_solr
c:rulesColl s:shard1 x:rulesColl_shard1_replica_n2] o.a.s.s.HttpSolrCall
[admin] webapp=null path=/admin/cores
params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=rulesColl_shard1_replica_n2&action=CREATE&numShards=1&collection=rulesColl&shard=shard1&wt=javabin&version=2&replicaType=NRT}
status=0 QTime=2299
[junit4] 2> 161417 INFO (qtp7482241-1202) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most
30 seconds. Check all shard replicas
[junit4] 2> 161518 INFO
(zkCallback-168-thread-2-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 161518 INFO
(zkCallback-166-thread-2-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader A cluster state change:
[WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/rulesColl/state.json] for collection [rulesColl] has occurred
- updating... (live nodes size: [5])
[junit4] 2> 162419 INFO (qtp7482241-1202) [n:127.0.0.1:55500_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections
params={shards=shard1&replicationFactor=2&collection.configName=conf&snitch=class:ImplicitSnitch&name=rulesColl&router.name=implicit&nrtReplicas=2&action=CREATE&rule=cores:<4&rule=node:*,replica:<2&rule=freedisk:>1&wt=javabin&version=2}
status=0 QTime=3871
[junit4] 2> 162427 INFO (qtp7482241-1208) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.CollectionsHandler Invoked Collection Action :createshard with params
action=CREATESHARD&collection=rulesColl&shard=shard2&wt=javabin&version=2 and
sendToOCPQueue=true
[junit4] 2> 162430 INFO
(OverseerCollectionConfigSetProcessor-98117815077109772-127.0.0.1:55510_solr-n_0000000000)
[n:127.0.0.1:55510_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> 162430 INFO
(OverseerThreadFactory-529-thread-5-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.CreateShardCmd Create shard invoked: {
[junit4] 2> "collection":"rulesColl",
[junit4] 2> "shard":"shard2",
[junit4] 2> "operation":"createshard"}
[junit4] 2> 162436 INFO (qtp7482241-1221) [n:127.0.0.1:55500_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=0, freedisk=19}
[junit4] 2> 162436 INFO (qtp7482241-1221) [n:127.0.0.1:55500_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={cores=1&action=INVOKE&class=org.apache.solr.cloud.rule.ImplicitSnitch&wt=javabin&version=2&freedisk=1}
status=0 QTime=2
[junit4] 2> 162441 INFO (qtp15820438-1193) [n:127.0.0.1:55502_solr ]
o.a.s.h.a.InvokeOp Invocable_invoked {cores=1, freedisk=19}
[junit4] 2> 162441 INFO (qtp15820438-1193) [n:127.0.0.
[...truncated too long message...]
junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 477414 INFO (qtp7482241-1200) [n:127.0.0.1:55500_solr ]
o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores
params={nodeName=127.0.0.1:55510_solr&onlyIfLeaderActive=true&core=rulesColl_shard2_replica_n1&coreNodeName=core_node5&action=PREPRECOVERY&checkLive=true&state=recovering&onlyIfLeader=true&wt=javabin&version=2}
status=400 QTime=21027
[junit4] 2> 477423 INFO (jetty-closer-144-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm,
tag=null
[junit4] 2> 477429 WARN
(zkCallback-166-thread-3-processing-n:127.0.0.1:55502_solr)
[n:127.0.0.1:55502_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 477430 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c95a209cf000d, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 477431 INFO (jetty-closer-144-thread-3) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@7c70b9{/solr,null,UNAVAILABLE}
[junit4] 2> 477431 INFO
(zkCallback-167-thread-4-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (4)
[junit4] 2> 477431 INFO (jetty-closer-144-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty,
tag=null
[junit4] 2> 477449 INFO (jetty-closer-144-thread-2) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 477449 INFO
(zkCallback-168-thread-4-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (4)
[junit4] 2> 477450 INFO
(zkCallback-169-thread-3-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (4)
[junit4] 2> 477450 INFO
(zkCallback-170-thread-3-processing-n:127.0.0.1:55500_solr)
[n:127.0.0.1:55500_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (5) -> (4)
[junit4] 2> 477450 INFO (jetty-closer-144-thread-5) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 477451 INFO (jetty-closer-144-thread-1) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 477456 INFO (jetty-closer-144-thread-4) [ ]
o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster,
tag=null
[junit4] 2> 477460 INFO (jetty-closer-144-thread-2) [ ]
o.a.s.c.Overseer Overseer
(id=98117815077109772-127.0.0.1:55510_solr-n_0000000000) closing
[junit4] 2> 477460 INFO
(OverseerStateUpdate-98117815077109772-127.0.0.1:55510_solr-n_0000000000)
[n:127.0.0.1:55510_solr ] o.a.s.c.Overseer Overseer Loop exiting :
127.0.0.1:55510_solr
[junit4] 2> 477462 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c95a209cf000b, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 477463 INFO
(zkCallback-169-thread-3-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 477463 INFO
(zkCallback-170-thread-3-processing-n:127.0.0.1:55500_solr)
[n:127.0.0.1:55500_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 477463 INFO
(zkCallback-167-thread-4-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from
ZooKeeper... (4) -> (3)
[junit4] 2> 477464 WARN
(zkCallback-169-thread-3-processing-n:127.0.0.1:55514_solr)
[n:127.0.0.1:55514_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 477464 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c95a209cf000c, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 477464 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ]
o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from
client sessionid 0x15c95a209cf000e, likely client has closed socket
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at
org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 477465 INFO (jetty-closer-144-thread-4) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@117c064{/solr,null,UNAVAILABLE}
[junit4] 2> 478958 WARN
(zkCallback-168-thread-3-processing-n:127.0.0.1:55511_solr)
[n:127.0.0.1:55511_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 478958 INFO (jetty-closer-144-thread-1) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@3544ad{/solr,null,UNAVAILABLE}
[junit4] 2> 478963 WARN
(zkCallback-167-thread-4-processing-n:127.0.0.1:55510_solr)
[n:127.0.0.1:55510_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 478963 INFO (jetty-closer-144-thread-2) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@a700b4{/solr,null,UNAVAILABLE}
[junit4] 2> 478964 WARN
(zkCallback-170-thread-3-processing-n:127.0.0.1:55500_solr)
[n:127.0.0.1:55500_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered,
but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 478965 INFO (jetty-closer-144-thread-5) [ ]
o.e.j.s.h.ContextHandler Stopped
o.e.j.s.ServletContextHandler@132f62c{/solr,null,UNAVAILABLE}
[junit4] 2> 478966 ERROR (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so
ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 478966 INFO (SUITE-RulesTest-seed#[5AA0C772C2D36C14]-worker)
[ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:55496 55496
[junit4] 2> 478968 INFO (Thread-258) [ ] o.a.s.c.ZkTestServer
connecting to 127.0.0.1:55496 55496
[junit4] 2> 478968 WARN (Thread-258) [ ] o.a.s.c.ZkTestServer Watch
limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/clusterprops.json
[junit4] 2> 12 /solr/aliases.json
[junit4] 2> 10 /solr/configs/conf
[junit4] 2> 5 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/clusterstate.json
[junit4] 2> 5 /solr/collections/rulesColl/state.json
[junit4] 2> 2 /solr/collections/modifyColl/state.json
[junit4] 2> 2 /solr/collections/hostFragment/state.json
[junit4] 2> 2
/solr/overseer_elect/election/98117815077109772-127.0.0.1:55510_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 39 /solr/collections
[junit4] 2> 12 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.rule.RulesTest_5AA0C772C2D36C14-001
[junit4] 2> Jun 11, 2017 5:36:10 AM
com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked
thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {},
docValues:{}, maxPointsInLeafNode=1793, maxMBSortInHeap=5.911721977461083,
sim=RandomSimilarity(queryNorm=false): {}, locale=es, timezone=Europe/Belfast
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 1.8.0_131
(32-bit)/cpus=3,threads=1,free=97115592,total=310902784
[junit4] 2> NOTE: All tests run in this JVM: [TestConfigSetImmutable,
TestUpdate, TestOrdValues, RecoveryZkTest,
OverseerCollectionConfigSetProcessorTest, MultiTermTest,
HdfsBasicDistributedZk2Test, DistribCursorPagingTest, CleanupOldIndexTest,
TestSweetSpotSimilarityFactory, HdfsRestartWhileUpdatingTest,
MultiThreadedOCPTest, XmlUpdateRequestHandlerTest, TestQueryWrapperFilter,
RulesTest]
[junit4] Completed [86/724 (1!)] on J0 in 327.60s, 6 tests, 1 failure, 1
error <<< FAILURES!
[...truncated 41485 lines...]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
