You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/05/28 02:35:07 UTC
[JENKINS-EA] Lucene-Solr-master-Windows (32bit/jdk-9-ea+171) -
Build # 6596 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6596/
Java: 32bit/jdk-9-ea+171 -server -XX:+UseConcMarkSweepGC
2 tests failed.
FAILED: org.apache.solr.cloud.CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap
Error Message:
Document mismatch on target after sync expected:<1000> but was:<0>
Stack Trace:
java.lang.AssertionError: Document mismatch on target after sync expected:<1000> but was:<0>
at __randomizedtesting.SeedInfo.seed([A255C006706ED11D:7582EF71C431495A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.cloud.CdcrBootstrapTest.testConvertClusterToCdcrAndBootstrap(CdcrBootstrapTest.java:134)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:563)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
FAILED: org.apache.solr.handler.admin.MBeansHandlerTest.testDiff
Error Message:
Stack Trace:
java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([A255C006706ED11D:6743049D60D8E97D]:0)
at org.apache.solr.handler.admin.SolrInfoMBeanHandler.diffObject(SolrInfoMBeanHandler.java:240)
at org.apache.solr.handler.admin.SolrInfoMBeanHandler.diffNamedList(SolrInfoMBeanHandler.java:219)
at org.apache.solr.handler.admin.SolrInfoMBeanHandler.getDiff(SolrInfoMBeanHandler.java:187)
at org.apache.solr.handler.admin.SolrInfoMBeanHandler.handleRequestBody(SolrInfoMBeanHandler.java:87)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
at org.apache.solr.core.SolrCore.execute(SolrCore.java:2487)
at org.apache.solr.util.TestHarness.query(TestHarness.java:337)
at org.apache.solr.util.TestHarness.query(TestHarness.java:319)
at org.apache.solr.handler.admin.MBeansHandlerTest.testDiff(MBeansHandlerTest.java:57)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:563)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 1708 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\temp\junit4-J0-20170528_011028_981227863711845521584.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\core\test\temp\junit4-J1-20170528_011028_9811420396261000620856.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 299 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\test-framework\test\temp\junit4-J1-20170528_011535_84512855843517349413388.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\test-framework\test\temp\junit4-J0-20170528_011535_84516313009080041987623.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1034 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\common\test\temp\junit4-J1-20170528_011627_27211130076259096557431.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\common\test\temp\junit4-J0-20170528_011627_27210849520459779584357.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 220 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\icu\test\temp\junit4-J1-20170528_011817_74410165646218564564709.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\icu\test\temp\junit4-J0-20170528_011817_74411616287850863176241.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 238 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\kuromoji\test\temp\junit4-J1-20170528_011824_6813950015641349906102.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\kuromoji\test\temp\junit4-J0-20170528_011824_6811759243739537605527.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 147 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\morfologik\test\temp\junit4-J0-20170528_011841_55315140199530189770250.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\morfologik\test\temp\junit4-J1-20170528_011841_55310670019377812479452.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 159 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\phonetic\test\temp\junit4-J1-20170528_011845_07317500928265689166047.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\phonetic\test\temp\junit4-J0-20170528_011845_0733539517982489118588.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 144 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\smartcn\test\temp\junit4-J1-20170528_011850_438466392851199351471.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\smartcn\test\temp\junit4-J0-20170528_011850_4377554127920314779373.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 152 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\stempel\test\temp\junit4-J1-20170528_011855_8239264048997976359052.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\stempel\test\temp\junit4-J0-20170528_011855_8235631336770112198407.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 160 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\analysis\uima\test\temp\junit4-J0-20170528_011858_55416970631359720385939.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 212 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\backward-codecs\test\temp\junit4-J1-20170528_011909_09215828246476947057632.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 26 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\backward-codecs\test\temp\junit4-J0-20170528_011909_09213513032525980950438.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1369 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\benchmark\test\temp\junit4-J0-20170528_012039_24713488572348565408977.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\benchmark\test\temp\junit4-J1-20170528_012039_24711680648142041202697.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 224 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\classification\test\temp\junit4-J0-20170528_012046_7503986339291048270190.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\classification\test\temp\junit4-J1-20170528_012046_7505628018112397013598.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 252 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\codecs\test\temp\junit4-J1-20170528_012106_6131413728327456557258.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\codecs\test\temp\junit4-J0-20170528_012106_6134799771819732138870.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 229 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\demo\test\temp\junit4-J0-20170528_012209_78313444012360845522141.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\demo\test\temp\junit4-J1-20170528_012209_7838835274267671901913.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 163 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\expressions\test\temp\junit4-J0-20170528_012212_9367999112165538727707.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\expressions\test\temp\junit4-J1-20170528_012212_9363752750820824888460.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 208 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\facet\test\temp\junit4-J0-20170528_012217_48914450853079551143110.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\facet\test\temp\junit4-J1-20170528_012217_4891155478524156078500.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 172 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\grouping\test\temp\junit4-J1-20170528_012234_12710162318225744284764.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\grouping\test\temp\junit4-J0-20170528_012234_12713700934966704951368.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 236 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\highlighter\test\temp\junit4-J1-20170528_012239_9671338834354043673946.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\highlighter\test\temp\junit4-J0-20170528_012239_9678491577977446748046.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 151 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\join\test\temp\junit4-J1-20170528_012250_0666483522207915518049.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 6 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\join\test\temp\junit4-J0-20170528_012250_06617818685848188027840.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 142 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\memory\test\temp\junit4-J0-20170528_012302_5153305596482538885860.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\memory\test\temp\junit4-J1-20170528_012302_51512457266215870551024.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 174 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\misc\test\temp\junit4-J0-20170528_012308_6194267565294679947417.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\misc\test\temp\junit4-J1-20170528_012308_61917864516441846451620.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 308 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\queries\test\temp\junit4-J1-20170528_012323_7046779459086868409368.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\queries\test\temp\junit4-J0-20170528_012323_7043692759542404444785.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 217 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\queryparser\test\temp\junit4-J1-20170528_012330_76713077061020179823113.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\queryparser\test\temp\junit4-J0-20170528_012330_76713899432137623753107.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 180 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\temp\junit4-J0-20170528_012337_34314440950082085198531.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 23 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\replicator\test\temp\junit4-J1-20170528_012337_34211517499400192387669.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 186 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\sandbox\test\temp\junit4-J0-20170528_012349_0388725062226115476735.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 21 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\sandbox\test\temp\junit4-J1-20170528_012349_03812358492291181415351.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 214 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial-extras\test\temp\junit4-J0-20170528_012408_73513921706650033373209.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial-extras\test\temp\junit4-J1-20170528_012408_73512184686488197317357.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 163 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial3d\test\temp\junit4-J0-20170528_012419_31117801320587548541393.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 5 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial3d\test\temp\junit4-J1-20170528_012419_3114975711649040319360.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 139 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\spatial\test\temp\junit4-J0-20170528_012437_11417765993253257826260.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 298 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\suggest\test\temp\junit4-J1-20170528_012441_09917417078017769390428.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\lucene\build\suggest\test\temp\junit4-J0-20170528_012441_09917603692780206743379.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 2626 lines...]
[junit4] Suite: org.apache.solr.handler.admin.MBeansHandlerTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.admin.MBeansHandlerTest_A255C006706ED11D-001\init-core-data-001
[junit4] 2> 2234957 WARN (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 2234957 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 2234959 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2234960 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2234961 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2235005 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 2235038 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test
[junit4] 2> 2235120 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2235203 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@db395b
[junit4] 2> 2235208 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@db395b
[junit4] 2> 2235208 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@db395b
[junit4] 2> 2235208 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib, /C:/Users/jenkins/workspace/Lucene-Solr-master-Windows/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2235243 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 2235270 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 2235380 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2235391 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1, trusted=true
[junit4] 2> 2235391 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@db395b
[junit4] 2> 2235392 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2235392 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.handler.admin.MBeansHandlerTest_A255C006706ED11D-001\init-core-data-001\]
[junit4] 2> 2235393 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=46, maxMergedSegmentMB=16.0439453125, floorSegmentMB=1.40625, forceMergeDeletesPctAllowed=21.045152320834674, segmentsPerTier=43.0, maxCFSSegmentSizeMB=1.6064453125, noCFSRatio=0.4217883935878012
[junit4] 2> 2235499 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2235499 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2235500 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2235500 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2235502 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=3, maxMergeAtOnceExplicit=4, maxMergedSegmentMB=45.923828125, floorSegmentMB=0.236328125, forceMergeDeletesPctAllowed=16.703236434971203, segmentsPerTier=21.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6217520796940393
[junit4] 2> 2235502 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@405e5b[collection1] main]
[junit4] 2> 2235506 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2235516 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2235565 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2235566 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2235566 INFO (coreLoadExecutor-7672-thread-1) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568603635858800640
[junit4] 2> 2235567 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2235567 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2235568 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2235568 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2235569 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2235570 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 2235570 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2235571 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2235574 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2235574 INFO (TEST-MBeansHandlerTest.testDiff-seed#[A255C006706ED11D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDiff
[junit4] 2> 2235576 INFO (searcherExecutor-7673-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@405e5b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2235580 INFO (TEST-MBeansHandlerTest.testDiff-seed#[A255C006706ED11D]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2235581 INFO (TEST-MBeansHandlerTest.testDiff-seed#[A255C006706ED11D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/admin/mbeans&stats=true&wt=xml} status=0 QTime=7
[junit4] 2> 2235599 ERROR (TEST-MBeansHandlerTest.testDiff-seed#[A255C006706ED11D]) [ ] o.a.s.h.RequestHandlerBase java.lang.NullPointerException
[junit4] 2> at org.apache.solr.handler.admin.SolrInfoMBeanHandler.diffObject(SolrInfoMBeanHandler.java:240)
[junit4] 2> at org.apache.solr.handler.admin.SolrInfoMBeanHandler.diffNamedList(SolrInfoMBeanHandler.java:219)
[junit4] 2> at org.apache.solr.handler.admin.SolrInfoMBeanHandler.getDiff(SolrInfoMBeanHandler.java:187)
[junit4] 2> at org.apache.solr.handler.admin.SolrInfoMBeanHandler.handleRequestBody(SolrInfoMBeanHandler.java:87)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2487)
[junit4] 2> at org.apache.solr.util.TestHarness.query(TestHarness.java:337)
[junit4] 2> at org.apache.solr.util.TestHarness.query(TestHarness.java:319)
[junit4] 2> at org.apache.solr.handler.admin.MBeansHandlerTest.testDiff(MBeansHandlerTest.java:57)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] 2> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] 2> at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.base/java.lang.reflect.Method.invoke(Method.java:563)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
[junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
[junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
[junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
[junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
[junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 2235600 INFO (TEST-MBeansHandlerTest.testDiff-seed#[A255C006706ED11D]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={qt=/admin/mbeans&stats=true&diff=true&wt=xml} status=500 QTime=15
[junit4] 2> 2235600 INFO (TEST-MBeansHandlerTest.testDiff-seed#[A255C006706ED11D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDiff
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=MBeansHandlerTest -Dtests.method=testDiff -Dtests.seed=A255C006706ED11D -Dtests.slow=true -Dtests.locale=fr-KM -Dtests.timezone=Antarctica/Mawson -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.03s J0 | MBeansHandlerTest.testDiff <<<
[junit4] > Throwable #1: java.lang.NullPointerException
[junit4] > at __randomizedtesting.SeedInfo.seed([A255C006706ED11D:6743049D60D8E97D]:0)
[junit4] > at org.apache.solr.handler.admin.SolrInfoMBeanHandler.diffObject(SolrInfoMBeanHandler.java:240)
[junit4] > at org.apache.solr.handler.admin.SolrInfoMBeanHandler.diffNamedList(SolrInfoMBeanHandler.java:219)
[junit4] > at org.apache.solr.handler.admin.SolrInfoMBeanHandler.getDiff(SolrInfoMBeanHandler.java:187)
[junit4] > at org.apache.solr.handler.admin.SolrInfoMBeanHandler.handleRequestBody(SolrInfoMBeanHandler.java:87)
[junit4] > at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:178)
[junit4] > at org.apache.solr.core.SolrCore.execute(SolrCore.java:2487)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:337)
[junit4] > at org.apache.solr.util.TestHarness.query(TestHarness.java:319)
[junit4] > at org.apache.solr.handler.admin.MBeansHandlerTest.testDiff(MBeansHandlerTest.java:57)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:563)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2235603 INFO (TEST-MBeansHandlerTest.testXMLDiffWithExternalEntity-seed#[A255C006706ED11D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testXMLDiffWithExternalEntity
[junit4] 2> 2235603 INFO (TEST-MBeansHandlerTest.testXMLDiffWithExternalEntity-seed#[A255C006706ED11D]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testXMLDiffWithExternalEntity
[junit4] 2> 2235603 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 2235603 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=22440491
[junit4] 2> 2235603 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2235608 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2235610 INFO (SUITE-MBeansHandlerTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2235611 INFO (coreCloseExecutor-7678-thread-1) [ ] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@162c466
[junit4] 2> 2235612 INFO (coreCloseExecutor-7678-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=23250022
[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.handler.admin.MBeansHandlerTest_A255C006706ED11D-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=197, maxMBSortInHeap=5.883305587068135, sim=RandomSimilarity(queryNorm=true): {}, locale=fr-KM, timezone=Antarctica/Mawson
[junit4] 2> NOTE: Windows 10 10.0 x86/Oracle Corporation 9-ea (32-bit)/cpus=3,threads=1,free=270169168,total=419045376
[junit4] 2> NOTE: All tests run in this JVM: [ConnectionManagerTest, TestDistributedStatsComponentCardinality, DocValuesTest, TestReplicationHandler, TestOverriddenPrefixQueryForCustomFieldType, TestManagedSchemaThreadSafety, TestFastOutputStream, TestCloudInspectUtil, SampleTest, SimpleFacetsTest, ZkControllerTest, TestCloudPivotFacet, TestSolrCloudWithKerberosAlt, TestConfigsApi, TestSolrCloudWithHadoopAuthPlugin, PluginInfoTest, SolrMetricManagerTest, JsonValidatorTest, BasicAuthStandaloneTest, HighlighterTest, ZkStateWriterTest, MoreLikeThisHandlerTest, TestScoreJoinQPNoScore, NodeMutatorTest, CopyFieldTest, HdfsTlogReplayBufferedWhileIndexingTest, SolrCoreTest, DistributedQueueTest, TestCorePropertiesReload, TestSlowCompositeReaderWrapper, CloudExitableDirectoryReaderTest, TestPayloadScoreQParserPlugin, ClusterStateUpdateTest, TestLRUStatsCache, TestXIncludeConfig, HdfsRecoveryZkTest, ExplicitHLLTest, TermsComponentTest, ClassificationUpdateProcessorFactoryTest, TestFunctionQuery, TestConfigReload, TestStressLucene, TestClusterStateMutator, BigEndianAscendingWordSerializerTest, TestAuthenticationFramework, TestRandomRequestDistribution, HdfsBasicDistributedZkTest, TestPullReplicaErrorHandling, V2ApiIntegrationTest, TestLegacyFieldCache, HardAutoCommitTest, ExitableDirectoryReaderTest, TestMaxScoreQueryParser, TestFieldCacheSort, BufferStoreTest, FastVectorHighlighterTest, TestFaceting, TestRestManager, TestLeaderElectionWithEmptyReplica, TestExtendedDismaxParser, CurrencyFieldXmlFileTest, TestDistributedSearch, TestSegmentSorting, TestSchemaSimilarityResource, CursorPagingTest, TestCSVResponseWriter, TestExportWriter, QueryResultKeyTest, TestElisionMultitermQuery, TestHdfsUpdateLog, TestSolrConfigHandlerConcurrent, TestConfigSetProperties, TestSurroundQueryParser, TestXmlQParser, EnumFieldTest, OverseerModifyCollectionTest, OverriddenZkACLAndCredentialsProvidersTest, TestAuthorizationFramework, SolrSlf4jReporterTest, TestReplicationHandlerBackup, DisMaxRequestHandlerTest, PingRequestHandlerTest, TestNamedUpdateProcessors, SaslZkACLProviderTest, TestSolrJ, WrapperMergePolicyFactoryTest, TestDefaultStatsCache, TestRandomDVFaceting, SolrRequestParserTest, TestJettySolrRunner, HdfsLockFactoryTest, SpellCheckCollatorWithCollapseTest, TestNumericTerms64, TestReloadDeadlock, UUIDUpdateProcessorFallbackTest, DateMathParserTest, TestEmbeddedSolrServerConstructors, TestSizeLimitedDistributedMap, TestCollationField, TestMergePolicyConfig, IndexBasedSpellCheckerTest, TestDFISimilarityFactory, DateFieldTest, TestSimpleQParserPlugin, HLLSerializationTest, TestRTGBase, TestIBSimilarityFactory, DocumentAnalysisRequestHandlerTest, TestStressVersions, CollectionsAPIAsyncDistributedZkTest, SolrGraphiteReporterTest, MultiTermTest, TestConfigSetsAPIExclusivity, TestPartialUpdateDeduplication, TestGraphTermsQParserPlugin, TestFieldCache, SimplePostToolTest, HttpPartitionTest, CachingDirectoryFactoryTest, TestFieldCacheVsDocValues, TestStressCloudBlindAtomicUpdates, RequiredFieldsTest, TestRestoreCore, TestBulkSchemaConcurrent, DistributedQueryElevationComponentTest, MoveReplicaTest, TestUtils, PropertiesRequestHandlerTest, TestPerFieldSimilarityWithDefaultOverride, DistributedSpellCheckComponentTest, TestRemoteStreaming, TestDynamicLoading, SimpleCollectionCreateDeleteTest, LeaderInitiatedRecoveryOnCommitTest, SyncSliceTest, TestSystemIdResolver, CdcrRequestHandlerTest, TestRTimerTree, SolrIndexConfigTest, ShufflingReplicaListTransformerTest, SimpleMLTQParserTest, TestManagedSynonymGraphFilterFactory, TestSchemaNameResource, RollingRestartTest, TestLeaderInitiatedRecoveryThread, TestShardHandlerFactory, TestCloudNestedDocsSort, TestStressLiveNodes, TestPostingsSolrHighlighter, TestBlendedInfixSuggestions, BlockCacheTest, TestCollectionAPI, AnalysisAfterCoreReloadTest, MergeStrategyTest, XmlUpdateRequestHandlerTest, SpellingQueryConverterTest, QueryElevationComponentTest, TestChildDocTransformer, TestTestInjection, SolrTestCaseJ4Test, ZkCLITest, TestCharFilters, PathHierarchyTokenizerFactoryTest, TestSolrFieldCacheBean, SoftAutoCommitTest, TestSolrCloudSnapshots, TestSolr4Spatial2, DocumentBuilderTest, RequestLoggingTest, LeaderElectionTest, TestCursorMarkWithoutUniqueKey, SynonymTokenizerTest, BasicDistributedZkTest, UnloadDistributedZkTest, OverseerTest, ShardRoutingTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, TestZkChroot, ShardRoutingCustomTest, TestHashPartitioner, TestStressReorder, TestRangeQuery, TestCoreContainer, TestSolr4Spatial, StatsComponentTest, SpellCheckComponentTest, TestGroupingSearch, SolrCmdDistributorTest, PeerSyncTest, TestSort, TestFiltering, TestLazyCores, DirectUpdateHandlerTest, ShowFileRequestHandlerTest, TestCoreDiscovery, SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, SuggesterTSTTest, SuggesterTest, SpatialFilterTest, TestCSVLoader, PolyFieldTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestPseudoReturnFields, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, DocValuesMultiTest, DistanceFunctionTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, DebugComponentTest, StandardRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, TestQueryTypes, PrimitiveFieldTypeTest, RequestHandlersTest, TestValueSourceCache, TestSolrQueryParser, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, SolrPluginUtilsTest, TestJmxIntegration, ReturnFieldsTest, MBeansHandlerTest]
[junit4] Completed [411/715 (1!)] on J0 in 0.70s, 2 tests, 1 error <<< FAILURES!
[...truncated 258 lines...]
[junit4] Suite: org.apache.solr.cloud.CdcrBootstrapTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrBootstrapTest_A255C006706ED11D-001\init-core-data-001
[junit4] 2> 2385190 WARN (SUITE-CdcrBootstrapTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 2385190 INFO (SUITE-CdcrBootstrapTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields
[junit4] 2> 2385192 INFO (SUITE-CdcrBootstrapTest-seed#[A255C006706ED11D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2385193 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[A255C006706ED11D]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBootstrapWithContinousIndexingOnSourceCluster
[junit4] 2> 2385194 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[A255C006706ED11D]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrBootstrapTest_A255C006706ED11D-001\cdcr-target-001
[junit4] 2> 2385194 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[A255C006706ED11D]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2385194 INFO (Thread-3828) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2385195 INFO (Thread-3828) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2385198 ERROR (Thread-3828) [ ] 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> 2385295 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[A255C006706ED11D]) [ ] o.a.s.c.ZkTestServer start zk server on port:58286
[junit4] 2> 2385303 INFO (jetty-launcher-3978-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2385305 INFO (jetty-launcher-3978-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c32f47{/solr,null,AVAILABLE}
[junit4] 2> 2385308 INFO (jetty-launcher-3978-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@418d9e{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2385308 INFO (jetty-launcher-3978-thread-1) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2385308 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58290}
[junit4] 2> 2385309 ERROR (jetty-launcher-3978-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2385309 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 2385309 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2385309 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2385309 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-28T02:05:49.812372300Z
[junit4] 2> 2385313 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2385320 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 0x15c4ccd6b050001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2385323 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58286/solr
[junit4] 2> 2385350 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2385351 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58290_solr
[junit4] 2> 2385353 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98037737042804739-127.0.0.1:58290_solr-n_0000000000) starting
[junit4] 2> 2385361 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58290_solr
[junit4] 2> 2385364 INFO (zkCallback-3985-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2385646 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58290.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ffb13b
[junit4] 2> 2385650 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58290.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ffb13b
[junit4] 2> 2385650 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58290.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ffb13b
[junit4] 2> 2385651 INFO (jetty-launcher-3978-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrBootstrapTest_A255C006706ED11D-001\cdcr-target-001\node1\.
[junit4] 1> Target zkHost = 127.0.0.1:58286/solr
[junit4] 2> 2385681 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[A255C006706ED11D]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrBootstrapTest_A255C006706ED11D-001\cdcr-source-001
[junit4] 2> 2385682 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[A255C006706ED11D]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2385682 INFO (Thread-3841) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2385683 INFO (Thread-3841) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2385691 ERROR (Thread-3841) [ ] 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> 2385783 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[A255C006706ED11D]) [ ] o.a.s.c.ZkTestServer start zk server on port:58310
[junit4] 2> 2385790 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 0x15c4ccd6cf20000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2385792 INFO (jetty-launcher-3990-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2385793 INFO (jetty-launcher-3990-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1e9f4fe{/solr,null,AVAILABLE}
[junit4] 2> 2385794 INFO (jetty-launcher-3990-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3ee01b{HTTP/1.1,[http/1.1]}{127.0.0.1:?????}
[junit4] 2> 2385794 INFO (jetty-launcher-3990-thread-1) [ ] o.e.j.s.Server Started @???????ms
[junit4] 2> 2385794 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=58314}
[junit4] 2> 2385794 ERROR (jetty-launcher-3990-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2385794 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 2385795 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2385795 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2385795 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-28T02:05:50.298976100Z
[junit4] 2> 2385800 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2385808 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 0x15c4ccd6cf20001, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2385812 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:58310/solr
[junit4] 2> 2385851 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2385853 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58314_solr
[junit4] 2> 2385855 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98037737075113987-127.0.0.1:58314_solr-n_0000000000) starting
[junit4] 2> 2385864 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58314_solr
[junit4] 2> 2385868 INFO (OverseerStateUpdate-98037737075113987-127.0.0.1:58314_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2386351 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58314.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ffb13b
[junit4] 2> 2386355 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58314.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ffb13b
[junit4] 2> 2386355 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58314.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ffb13b
[junit4] 2> 2386357 INFO (jetty-launcher-3990-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrBootstrapTest_A255C006706ED11D-001\cdcr-source-001\node1\.
[junit4] 2> 2386404 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 0x15c4ccd6cf20006, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2386409 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[A255C006706ED11D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2386410 INFO (TEST-CdcrBootstrapTest.testBootstrapWithContinousIndexingOnSourceCluster-seed#[A255C006706ED11D]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:58310/solr ready
[junit4] 2> 2386418 INFO (qtp27969310-20194) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=1&collection.configName=cdcr-source&name=cdcr-source&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2386425 INFO (OverseerThreadFactory-7918-thread-1) [ ] o.a.s.c.CreateCollectionCmd Create collection cdcr-source
[junit4] 2> 2386534 INFO (qtp27969310-20197) [ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=cdcr-source&newCollection=true&name=cdcr-source_shard1_replica_n1&action=CREATE&numShards=1&collection=cdcr-source&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2386535 INFO (qtp27969310-20197) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2386642 INFO (zkCallback-3997-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/state.json] for collection [cdcr-source] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2387548 INFO (qtp27969310-20197) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.0.0
[junit4] 2> 2387568 INFO (qtp27969310-20197) [ ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 2387573 INFO (qtp27969310-20197) [ ] o.a.s.s.IndexSchema [cdcr-source_shard1_replica_n1] Schema name=minimal
[junit4] 2> 2387575 INFO (qtp27969310-20197) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 2387576 INFO (qtp27969310-20197) [ ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/cdcr-source/managed-schema
[junit4] 2> 2387579 INFO (qtp27969310-20197) [ ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/cdcr-source/schema.xml to /configs/cdcr-source/schema.xml.bak
[junit4] 2> 2387579 INFO (qtp27969310-20197) [ ] o.a.s.c.CoreContainer Creating SolrCore 'cdcr-source_shard1_replica_n1' using configuration from collection cdcr-source, trusted=true
[junit4] 2> 2387581 INFO (qtp27969310-20197) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_58314.solr.core.cdcr-source.shard1.replica_n1' (registry 'solr.core.cdcr-source.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ffb13b
[junit4] 2> 2387581 INFO (qtp27969310-20197) [ ] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 2387581 INFO (qtp27969310-20197) [ ] o.a.s.c.SolrCore [[cdcr-source_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrBootstrapTest_A255C006706ED11D-001\cdcr-source-001\node1\cdcr-source_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.CdcrBootstrapTest_A255C006706ED11D-001\cdcr-source-001\node1\.\cdcr-source_shard1_replica_n1\data\]
[junit4] 2> 2387741 INFO (qtp27969310-20197) [ ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.CdcrUpdateLog
[junit4] 2> 2387741 INFO (qtp27969310-20197) [ ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2387745 INFO (qtp27969310-20197) [ ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2387745 INFO (qtp27969310-20197) [ ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2387752 INFO (qtp27969310-20197) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2363fc[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 2387752 INFO (qtp27969310-20197) [ ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cdcr-source
[junit4] 2> 2387756 INFO (qtp27969310-20197) [ ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cdcr-source
[junit4] 2> 2387756 INFO (qtp27969310-20197) [ ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cdcr-source/managed-schema
[junit4] 2> 2387757 INFO (qtp27969310-20197) [ ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2387758 INFO (qtp27969310-20197) [ ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 2387762 INFO (qtp27969310-20197) [ ] o.a.s.h.CdcrBufferStateManager Created znode /collections/cdcr-source/cdcr/state/buffer
[junit4] 2> 2387764 INFO (qtp27969310-20197) [ ] o.a.s.h.CdcrProcessStateManager Created znode /collections/cdcr-source/cdcr/state/process
[junit4] 2> 2387775 INFO (searcherExecutor-7921-thread-1) [ ] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@2363fc[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 2387775 INFO (qtp27969310-20197) [ ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1568603795387056128
[junit4] 2> 2387784 INFO (qtp27969310-20197) [ ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2387784 INFO (qtp27969310-20197) [ ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2387784 INFO (qtp27969310-20197) [ ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:58314/solr/cdcr-source_shard1_replica_n1/
[junit4] 2> 2387784 INFO (qtp27969310-20197) [ ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2387784 INFO (qtp27969310-20197) [ ] o.a.s.c.SyncStrategy http://127.0.0.1:58314/solr/cdcr-source_shard1_replica_n1/ has no replicas
[junit4] 2> 2387784 INFO (qtp27969310-20197) [ ] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 2387788 INFO (zkCallback-3997-thread-1) [ ] o.a.s.h.CdcrLeaderStateManager Received new leader state @ cdcr-source:shard1
[junit4] 2> 2387790 INFO (qtp27969310-20197) [ ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:58314/solr/cdcr-source_shard1_replica_n1/ shard1
[junit4] 2> 2387897 INFO (zkCallback-3997-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/state.json] for collection [cdcr-source] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2387943 INFO (qtp27969310-20197) [ ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2387943 INFO (qtp27969310-20197) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=cdcr-source&newCollection=true&name=cdcr-source_shard1_replica_n1&action=CREATE&numShards=1&collection=cdcr-source&shard=shard1&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1412
[junit4] 2> 2387949 INFO (qtp27969310-20194) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 2388050 INFO (zkCallback-3997-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/cdcr-source/state.json] for collection [cdcr-source] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2388426 INFO (OverseerCollectionConfigSetProcessor-98037737075113987-127.0.0.1:58314_solr-n_0000000000) [ ] 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> 2388950 INFO (qtp27969310-20194) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&replicationFactor=1&collection.configName=cdcr-source&name=cdcr-source&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&property.solr.directoryFactory=solr.StandardDirectoryFactory&wt=javabin&version=2} status=0 QTime=2532
[junit4] 1> Adding 10 docs with commit=true, numDocs=100
[junit4] 2> 2388963 INFO (qtp27969310-20198) [ ] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 2388964 INFO (qtp27969310-20199) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1568603796633812992,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2388964 INFO (qtp27969310-20199) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dedda commitCommandVersion:1568603796633812992
[junit4] 2> 2388994 INFO (qtp27969310-20199) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1b0e19e[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 2388995 INFO (qtp27969310-20199) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2388998 INFO (searcherExecutor-7921-thread-1) [ ] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@1b0e19e[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100)))}
[junit4] 2> 2388998 INFO (qtp27969310-20199) [ ] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=34
[junit4] 1> Adding 10 docs with commit=true, numDocs=200
[junit4] 2> 2389007 INFO (qtp27969310-20200) [ ] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 2389008 INFO (qtp27969310-20200) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1568603796679950336,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2389008 INFO (qtp27969310-20200) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dedda commitCommandVersion:1568603796679950336
[junit4] 2> 2389063 INFO (qtp27969310-20200) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@e5fdb6[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 2389064 INFO (qtp27969310-20200) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2389065 INFO (searcherExecutor-7921-thread-1) [ ] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@e5fdb6[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100)))}
[junit4] 2> 2389066 INFO (qtp27969310-20200) [ ] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=58
[junit4] 1> Adding 10 docs with commit=true, numDocs=300
[junit4] 2> 2389076 INFO (qtp27969310-20201) [ ] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=9
[junit4] 2> 2389077 INFO (qtp27969310-20196) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1568603796752302080,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2389078 INFO (qtp27969310-20196) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dedda commitCommandVersion:1568603796752302080
[junit4] 2> 2389112 INFO (qtp27969310-20196) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@16e036e[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 2389113 INFO (qtp27969310-20196) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2389114 INFO (searcherExecutor-7921-thread-1) [ ] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@16e036e[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100)))}
[junit4] 2> 2389114 INFO (qtp27969310-20196) [ ] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=37
[junit4] 1> Adding 10 docs with commit=true, numDocs=400
[junit4] 2> 2389122 INFO (qtp27969310-20197) [ ] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 2389123 INFO (qtp27969310-20194) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1568603796800536576,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2389123 INFO (qtp27969310-20194) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dedda commitCommandVersion:1568603796800536576
[junit4] 2> 2389158 INFO (qtp27969310-20194) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@18f2aee[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 2389159 INFO (qtp27969310-20194) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2389160 INFO (searcherExecutor-7921-thread-1) [ ] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@18f2aee[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(7.0.0):C100) Uninverting(_1(7.0.0):C100) Uninverting(_2(7.0.0):C100) Uninverting(_3(7.0.0):C100)))}
[junit4] 2> 2389161 INFO (qtp27969310-20194) [ ] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2} status=0 QTime=37
[junit4] 1> Adding 10 docs with commit=true, numDocs=500
[junit4] 2> 2389171 INFO (qtp27969310-20198) [ ] o.a.s.c.S.Request [cdcr-source_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=cdcr-source:3&wt=javabin&version=2} status=0 QTime=8
[junit4] 2> 2389172 INFO (qtp27969310-20199) [ ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1568603796851916800,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2389172 INFO (qtp27969310-20199) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@dedda commitCommandVersion:1568603796851916800
[junit4] 2> 2389228 INFO (qtp27969310-20199) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@1354383[cdcr-source_shard1_replica_n1] main]
[junit4] 2> 2389229 INFO (qtp27969310-20199) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 2389231 INFO (searcherExecutor-7921-thread-1) [ ] o.a.s.c.SolrCore [cdcr-source_shard1_replica_n1] Registered new searcher Searcher@1354383[cdcr-source_shard1_replica_n1] main{ExitableDirectoryReader(Uninve
[...truncated too long message...]
\temp\junit4-J1-20170528_022116_61318067176529122154140.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\temp\junit4-J0-20170528_022116_6135241439892720270897.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1236 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\temp\junit4-J0-20170528_022554_0223994917127902793739.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analysis-extras\test\temp\junit4-J1-20170528_022554_0221370696900933367609.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 521 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analytics\test\temp\junit4-J0-20170528_022606_2474019763450936885734.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 3 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-analytics\test\temp\junit4-J1-20170528_022606_2473672651226402382802.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 500 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-clustering\test\temp\junit4-J1-20170528_022625_30816189315876756999478.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-clustering\test\temp\junit4-J0-20170528_022625_30810081381212820480162.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 1130 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler-extras\test\temp\junit4-J1-20170528_022701_07513462841125789368968.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler-extras\test\temp\junit4-J0-20170528_022701_075222543181839587213.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 584 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\temp\junit4-J0-20170528_022713_80511857428858475473844.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 12 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-dataimporthandler\test\temp\junit4-J1-20170528_022713_80513233141270604565351.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 501 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-cell\test\temp\junit4-J1-20170528_022752_9308847029450961246327.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 6 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-cell\test\temp\junit4-J0-20170528_022752_9302041724392897425616.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 506 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-langid\test\temp\junit4-J1-20170528_022813_99012264361296821869227.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-langid\test\temp\junit4-J0-20170528_022813_9956172323329430176391.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 593 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\temp\junit4-J1-20170528_022825_7256979188744427835339.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-ltr\test\temp\junit4-J0-20170528_022825_7105716187416774858525.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 538 lines...]
[junit4] JVM J1: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-uima\test\temp\junit4-J1-20170528_022908_7348205275438503570683.syserr
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J1: EOF ----
[...truncated 3 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-uima\test\temp\junit4-J0-20170528_022908_73416307934848967439938.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 495 lines...]
[junit4] JVM J0: stderr was not empty, see: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\contrib\solr-velocity\test\temp\junit4-J0-20170528_022925_88716476759956172827916.syserr
[junit4] >>> JVM J0 emitted unexpected output (verbatim) ----
[junit4] Java HotSpot(TM) Server VM warning: Option UseConcMarkSweepGC was deprecated in version 9.0 and will likely be removed in a future release.
[junit4] <<< JVM J0: EOF ----
[...truncated 28807 lines...]
[JENKINS-EA] Lucene-Solr-master-Windows (64bit/jdk-9-ea+171) -
Build # 6597 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/6597/
Java: 64bit/jdk-9-ea+171 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.cloud.OverseerRolesTest.testOverseerRole
Error Message:
Timed out waiting for overseer state change
Stack Trace:
java.lang.AssertionError: Timed out waiting for overseer state change
at __randomizedtesting.SeedInfo.seed([302BADA830062B5E:D1E0503C0BB51D8F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:62)
at org.apache.solr.cloud.OverseerRolesTest.testOverseerRole(OverseerRolesTest.java:140)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:563)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 12617 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_302BADA830062B5E-001\init-core-data-001
[junit4] 2> 2666564 INFO (SUITE-OverseerRolesTest-seed#[302BADA830062B5E]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields
[junit4] 2> 2666566 INFO (SUITE-OverseerRolesTest-seed#[302BADA830062B5E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2666567 INFO (SUITE-OverseerRolesTest-seed#[302BADA830062B5E]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_302BADA830062B5E-001\tempDir-001
[junit4] 2> 2666568 INFO (SUITE-OverseerRolesTest-seed#[302BADA830062B5E]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2666568 INFO (Thread-4163) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2666568 INFO (Thread-4163) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2666572 ERROR (Thread-4163) [ ] 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> 2666669 INFO (SUITE-OverseerRolesTest-seed#[302BADA830062B5E]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:52650
[junit4] 2> 2666678 INFO (jetty-launcher-3649-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2666679 INFO (jetty-launcher-3649-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2666680 INFO (jetty-launcher-3649-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@350ccc38{/solr,null,AVAILABLE}
[junit4] 2> 2666681 INFO (jetty-launcher-3649-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4003b3fd{HTTP/1.1,[http/1.1]}{127.0.0.1:52654}
[junit4] 2> 2666681 INFO (jetty-launcher-3649-thread-2) [ ] o.e.j.s.Server Started @2668611ms
[junit4] 2> 2666681 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52654}
[junit4] 2> 2666681 ERROR (jetty-launcher-3649-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2666681 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 2666681 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2666681 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2666681 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-28T12:13:28.008609500Z
[junit4] 2> 2666684 INFO (jetty-launcher-3649-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2666684 INFO (jetty-launcher-3649-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6e4b9f4c{/solr,null,AVAILABLE}
[junit4] 2> 2666685 INFO (jetty-launcher-3649-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@30b95dfc{HTTP/1.1,[http/1.1]}{127.0.0.1:52659}
[junit4] 2> 2666685 INFO (jetty-launcher-3649-thread-3) [ ] o.e.j.s.Server Started @2668616ms
[junit4] 2> 2666685 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52659}
[junit4] 2> 2666686 ERROR (jetty-launcher-3649-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2666686 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 2666686 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2666686 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2666686 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-28T12:13:28.013634200Z
[junit4] 2> 2666689 INFO (jetty-launcher-3649-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2666690 INFO (jetty-launcher-3649-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f4be3c8{/solr,null,AVAILABLE}
[junit4] 2> 2666691 INFO (jetty-launcher-3649-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@66f02ecf{HTTP/1.1,[http/1.1]}{127.0.0.1:52664}
[junit4] 2> 2666691 INFO (jetty-launcher-3649-thread-4) [ ] o.e.j.s.Server Started @2668622ms
[junit4] 2> 2666691 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52664}
[junit4] 2> 2666691 ERROR (jetty-launcher-3649-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2666692 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 2666692 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2666692 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2666692 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-28T12:13:28.019737Z
[junit4] 2> 2666692 INFO (jetty-launcher-3649-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4abf494b{/solr,null,AVAILABLE}
[junit4] 2> 2666694 INFO (jetty-launcher-3649-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@37356b89{HTTP/1.1,[http/1.1]}{127.0.0.1:52669}
[junit4] 2> 2666694 INFO (jetty-launcher-3649-thread-1) [ ] o.e.j.s.Server Started @2668624ms
[junit4] 2> 2666694 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=52669}
[junit4] 2> 2666694 ERROR (jetty-launcher-3649-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2666695 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.0.0
[junit4] 2> 2666695 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2666695 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 2666695 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-05-28T12:13:28.022255400Z
[junit4] 2> 2666753 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2666760 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52650/solr
[junit4] 2> 2666763 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2666770 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52650/solr
[junit4] 2> 2666771 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2666778 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52650/solr
[junit4] 2> 2666780 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2666786 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:52650/solr
[junit4] 2> 2666859 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2666859 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2666859 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52659_solr
[junit4] 2> 2666864 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52654_solr
[junit4] 2> 2666865 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.c.Overseer Overseer (id=98040126367268873-127.0.0.1:52659_solr-n_0000000000) starting
[junit4] 2> 2666871 INFO (zkCallback-3668-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2666956 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52654.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2666959 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52654.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2666959 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52654.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2666960 INFO (jetty-launcher-3649-thread-2) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_302BADA830062B5E-001\tempDir-001\node2\.
[junit4] 2> 2666963 INFO (zkCallback-3669-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2666990 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2666990 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2666992 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52659_solr
[junit4] 2> 2667003 INFO (zkCallback-3668-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2667012 INFO (zkCallback-3669-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2667017 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2667052 INFO (zkCallback-3671-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 2667092 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52659.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2667095 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52659.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2667095 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52659.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2667096 INFO (jetty-launcher-3649-thread-3) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_302BADA830062B5E-001\tempDir-001\node3\.
[junit4] 2> 2667108 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2667113 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2667121 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52669_solr
[junit4] 2> 2667121 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52664_solr
[junit4] 2> 2667229 INFO (zkCallback-3671-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 2667238 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 2667259 INFO (zkCallback-3669-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 2667267 INFO (zkCallback-3668-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (4)
[junit4] 2> 2667307 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52669.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2667310 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52669.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2667310 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52669.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2667311 INFO (jetty-launcher-3649-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_302BADA830062B5E-001\tempDir-001\node1\.
[junit4] 2> 2667370 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52664.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2667374 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52664.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2667374 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_52664.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2ae1e724
[junit4] 2> 2667375 INFO (jetty-launcher-3649-thread-4) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_302BADA830062B5E-001\tempDir-001\node4\.
[junit4] 2> 2667413 INFO (SUITE-OverseerRolesTest-seed#[302BADA830062B5E]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2667416 INFO (SUITE-OverseerRolesTest-seed#[302BADA830062B5E]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:52650/solr ready
[junit4] 2> 2667446 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOverseerRole
[junit4] 2> 2667447 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:52659_solr
[junit4] 2> 2667448 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 2667449 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest ### Setting overseer designate 127.0.0.1:52654_solr
[junit4] 2> 2667453 INFO (qtp349394242-20945) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:52654_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2667459 INFO (OverseerThreadFactory-9582-thread-1) [ ] o.a.s.c.OverseerRoleCmd Overseer role added to 127.0.0.1:52654_solr
[junit4] 2> 2667510 INFO (qtp349394242-20945) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:52654_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=56
[junit4] 2> 2667515 INFO (Thread-4212) [ ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98040126367268873-127.0.0.1:52659_solr-n_0000000000 overseer designates are [127.0.0.1:52654_solr]
[junit4] 2> 2667516 INFO (Thread-4212) [ ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98040126367268873-127.0.0.1:52659_solr-n_0000000000, 98040126367268874-127.0.0.1:52654_solr-n_0000000001, 98040126367268876-127.0.0.1:52669_solr-n_0000000002, 98040126367268875-127.0.0.1:52664_solr-n_0000000003]
[junit4] 2> 2667520 INFO (OverseerStateUpdate-98040126367268873-127.0.0.1:52659_solr-n_0000000000) [ ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98040126367268873-127.0.0.1:52659_solr-n_0000000000"} 127.0.0.1:52659_solr
[junit4] 2> 2667522 INFO (OverseerStateUpdate-98040126367268873-127.0.0.1:52659_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52659_solr
[junit4] 2> 2667523 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2667524 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=98040126367268873-127.0.0.1:52659_solr-n_0000000000) closing
[junit4] 2> 2667527 INFO (zkCallback-3669-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52654_solr
[junit4] 2> 2667529 INFO (zkCallback-3669-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98040126367268874-127.0.0.1:52654_solr-n_0000000001) starting
[junit4] 2> 2667612 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest ### Adding another overseer designate 127.0.0.1:52664_solr
[junit4] 2> 2667613 INFO (qtp349394242-20964) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:52664_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2667616 INFO (OverseerThreadFactory-9588-thread-1) [ ] o.a.s.c.OverseerRoleCmd Overseer role added to 127.0.0.1:52664_solr
[junit4] 2> 2667619 INFO (qtp349394242-20964) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:52664_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 2667620 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:52654_solr
[junit4] 2> 2667621 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 2667621 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.ChaosMonkey monkey: stop jetty! 52654
[junit4] 2> 2667621 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1570281786
[junit4] 2> 2667621 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2667622 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2667624 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2667626 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2667626 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2667626 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.Overseer Overseer (id=98040126367268874-127.0.0.1:52654_solr-n_0000000001) closing
[junit4] 2> 2667626 INFO (OverseerCollectionConfigSetProcessor-98040126367268874-127.0.0.1:52654_solr-n_0000000001) [ ] 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> 2667627 INFO (OverseerStateUpdate-98040126367268874-127.0.0.1:52654_solr-n_0000000001) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52654_solr
[junit4] 2> 2667638 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 0x15c4ef9b9db000a, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2667638 ERROR (Thread-4213) [ ] o.a.s.c.OverseerRoleCmd Error in prioritizing Overseer
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:357)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:354)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:354)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor.getLeaderId(OverseerTaskProcessor.java:374)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor.getLeaderNode(OverseerTaskProcessor.java:365)
[junit4] 2> at org.apache.solr.cloud.OverseerNodePrioritizer.prioritizeOverseerNodes(OverseerNodePrioritizer.java:66)
[junit4] 2> at org.apache.solr.cloud.OverseerRoleCmd.lambda$call$0(OverseerRoleCmd.java:93)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2667641 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4003b3fd{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2667644 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@350ccc38{/solr,null,UNAVAILABLE}
[junit4] 2> 2667646 INFO (zkCallback-3671-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52669_solr
[junit4] 2> 2667646 INFO (zkCallback-3668-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2667646 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2667647 INFO (zkCallback-3679-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2667647 INFO (zkCallback-3671-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2667650 INFO (zkCallback-3671-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98040126367268876-127.0.0.1:52669_solr-n_0000000002) starting
[junit4] 2> 2667661 INFO (OverseerCollectionConfigSetProcessor-98040126367268876-127.0.0.1:52669_solr-n_0000000002) [ ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98040126367268876-127.0.0.1:52669_solr-n_0000000002 overseer designates are [127.0.0.1:52654_solr, 127.0.0.1:52664_solr]
[junit4] 2> 2667662 INFO (OverseerCollectionConfigSetProcessor-98040126367268876-127.0.0.1:52669_solr-n_0000000002) [ ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98040126367268876-127.0.0.1:52669_solr-n_0000000002, 98040126367268875-127.0.0.1:52664_solr-n_0000000003, 98040126367268873-127.0.0.1:52659_solr-n_0000000004]
[junit4] 2> 2667665 INFO (OverseerStateUpdate-98040126367268876-127.0.0.1:52669_solr-n_0000000002) [ ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98040126367268876-127.0.0.1:52669_solr-n_0000000002"} 127.0.0.1:52669_solr
[junit4] 2> 2667666 INFO (OverseerStateUpdate-98040126367268876-127.0.0.1:52669_solr-n_0000000002) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52669_solr
[junit4] 2> 2667668 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2667669 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=98040126367268876-127.0.0.1:52669_solr-n_0000000002) closing
[junit4] 2> 2667671 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52664_solr
[junit4] 2> 2667674 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98040126367268875-127.0.0.1:52664_solr-n_0000000003) starting
[junit4] 2> 2667750 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest ### Adding last overseer designate 127.0.0.1:52669_solr
[junit4] 2> 2667751 INFO (qtp349394242-20949) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:52669_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2667805 INFO (OverseerThreadFactory-9593-thread-1) [ ] o.a.s.c.OverseerRoleCmd Overseer role added to 127.0.0.1:52669_solr
[junit4] 2> 2667808 INFO (qtp349394242-20949) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:52669_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=57
[junit4] 2> 2667808 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:52664_solr
[junit4] 2> 2667809 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 2667810 INFO (qtp349394242-20948) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:52664_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2667813 INFO (OverseerCollectionConfigSetProcessor-98040126367268875-127.0.0.1:52664_solr-n_0000000003) [ ] 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> 2667818 INFO (OverseerThreadFactory-9593-thread-2) [ ] o.a.s.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:52664_solr
[junit4] 2> 2667821 INFO (qtp349394242-20948) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:52664_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 2667823 INFO (Thread-4216) [ ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98040126367268875-127.0.0.1:52664_solr-n_0000000003 overseer designates are [127.0.0.1:52654_solr, 127.0.0.1:52669_solr]
[junit4] 2> 2667823 INFO (Thread-4216) [ ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98040126367268875-127.0.0.1:52664_solr-n_0000000003, 98040126367268873-127.0.0.1:52659_solr-n_0000000004, 98040126367268876-127.0.0.1:52669_solr-n_0000000005]
[junit4] 2> 2667823 INFO (Thread-4216) [ ] o.a.s.c.OverseerNodePrioritizer asking node 98040126367268876-127.0.0.1:52669_solr-n_0000000005 to come join election at head
[junit4] 2> 2667825 INFO (qtp1353890890-20940) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=98040126367268876-127.0.0.1:52669_solr-n_0000000005&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2667825 INFO (Thread-4216) [ ] o.a.s.c.OverseerNodePrioritizer asking the old first in line 98040126367268873-127.0.0.1:52659_solr-n_0000000004 to rejoin election
[junit4] 2> 2667828 INFO (qtp593302210-20929) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=98040126367268873-127.0.0.1:52659_solr-n_0000000004&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2667831 INFO (OverseerStateUpdate-98040126367268875-127.0.0.1:52664_solr-n_0000000003) [ ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98040126367268875-127.0.0.1:52664_solr-n_0000000003"} 127.0.0.1:52664_solr
[junit4] 2> 2667833 INFO (OverseerStateUpdate-98040126367268875-127.0.0.1:52664_solr-n_0000000003) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52664_solr
[junit4] 2> 2667838 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2667840 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=98040126367268875-127.0.0.1:52664_solr-n_0000000003) closing
[junit4] 2> 2667842 INFO (zkCallback-3668-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52659_solr
[junit4] 2> 2667846 INFO (zkCallback-3668-thread-2) [ ] o.a.s.c.Overseer Overseer (id=98040126367268873-127.0.0.1:52659_solr-n_0000000004) starting
[junit4] 2> 2667854 INFO (OverseerCollectionConfigSetProcessor-98040126367268873-127.0.0.1:52659_solr-n_0000000004) [ ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98040126367268873-127.0.0.1:52659_solr-n_0000000004 overseer designates are [127.0.0.1:52654_solr, 127.0.0.1:52669_solr]
[junit4] 2> 2667854 INFO (OverseerCollectionConfigSetProcessor-98040126367268873-127.0.0.1:52659_solr-n_0000000004) [ ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98040126367268873-127.0.0.1:52659_solr-n_0000000004, 98040126367268876-127.0.0.1:52669_solr-n_0000000005, 98040126367268875-127.0.0.1:52664_solr-n_0000000006]
[junit4] 2> 2667858 INFO (OverseerStateUpdate-98040126367268873-127.0.0.1:52659_solr-n_0000000004) [ ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98040126367268873-127.0.0.1:52659_solr-n_0000000004"} 127.0.0.1:52659_solr
[junit4] 2> 2667858 INFO (OverseerStateUpdate-98040126367268873-127.0.0.1:52659_solr-n_0000000004) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52659_solr
[junit4] 2> 2667864 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2667866 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=98040126367268873-127.0.0.1:52659_solr-n_0000000004) closing
[junit4] 2> 2667868 INFO (zkCallback-3671-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52669_solr
[junit4] 2> 2667871 INFO (zkCallback-3671-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98040126367268876-127.0.0.1:52669_solr-n_0000000005) starting
[junit4] 2> 2667922 INFO (qtp349394242-20947) [ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:52664_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2667926 INFO (OverseerThreadFactory-9597-thread-1) [ ] o.a.s.c.OverseerRoleCmd Overseer role added to 127.0.0.1:52664_solr
[junit4] 2> 2667928 INFO (qtp349394242-20947) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:52664_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 2667929 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.c.OverseerRolesTest ### Sending QUIT to overseer 127.0.0.1:52669_solr
[junit4] 2> 2667932 INFO (OverseerCollectionConfigSetProcessor-98040126367268876-127.0.0.1:52669_solr-n_0000000005) [ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000008 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2667932 INFO (OverseerStateUpdate-98040126367268876-127.0.0.1:52669_solr-n_0000000005) [ ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98040126367268876-127.0.0.1:52669_solr-n_0000000005"} 127.0.0.1:52669_solr
[junit4] 2> 2667934 INFO (OverseerStateUpdate-98040126367268876-127.0.0.1:52669_solr-n_0000000005) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52669_solr
[junit4] 2> 2667940 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2667942 INFO (Thread-4217) [ ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98040126367268876-127.0.0.1:52669_solr-n_0000000005 overseer designates are [127.0.0.1:52654_solr, 127.0.0.1:52669_solr, 127.0.0.1:52664_solr]
[junit4] 2> 2667943 INFO (Thread-4217) [ ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98040126367268876-127.0.0.1:52669_solr-n_0000000005, 98040126367268875-127.0.0.1:52664_solr-n_0000000006, 98040126367268873-127.0.0.1:52659_solr-n_0000000007]
[junit4] 2> 2667943 INFO (Thread-4217) [ ] o.a.s.c.OverseerNodePrioritizer asking node 98040126367268876-127.0.0.1:52669_solr-n_0000000005 to come join election at head
[junit4] 2> 2667943 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=98040126367268876-127.0.0.1:52669_solr-n_0000000005) closing
[junit4] 2> 2667949 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52664_solr
[junit4] 2> 2667952 INFO (zkCallback-3670-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98040126367268875-127.0.0.1:52664_solr-n_0000000006) starting
[junit4] 2> 2667952 INFO (qtp1353890890-20940) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=98040126367268876-127.0.0.1:52669_solr-n_0000000005&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 2667953 INFO (Thread-4217) [ ] o.a.s.c.OverseerNodePrioritizer asking the old first in line 98040126367268875-127.0.0.1:52664_solr-n_0000000006 to rejoin election
[junit4] 2> 2667954 INFO (qtp349394242-20946) [ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=98040126367268875-127.0.0.1:52664_solr-n_0000000006&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2667969 INFO (OverseerStateUpdate-98040126367268875-127.0.0.1:52664_solr-n_0000000006) [ ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98040126367268875-127.0.0.1:52664_solr-n_0000000006"} 127.0.0.1:52664_solr
[junit4] 2> 2667970 INFO (OverseerStateUpdate-98040126367268875-127.0.0.1:52664_solr-n_0000000006) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52664_solr
[junit4] 2> 2667978 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2667979 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=98040126367268875-127.0.0.1:52664_solr-n_0000000006) closing
[junit4] 2> 2667980 INFO (zkCallback-3668-thread-2) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52659_solr
[junit4] 2> 2667981 INFO (zkCallback-3668-thread-2) [ ] o.a.s.c.Overseer Overseer (id=98040126367268873-127.0.0.1:52659_solr-n_0000000007) starting
[junit4] 2> 2667989 INFO (OverseerCollectionConfigSetProcessor-98040126367268873-127.0.0.1:52659_solr-n_0000000007) [ ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98040126367268873-127.0.0.1:52659_solr-n_0000000007 overseer designates are [127.0.0.1:52654_solr, 127.0.0.1:52669_solr, 127.0.0.1:52664_solr]
[junit4] 2> 2667990 INFO (OverseerCollectionConfigSetProcessor-98040126367268873-127.0.0.1:52659_solr-n_0000000007) [ ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98040126367268873-127.0.0.1:52659_solr-n_0000000007, 98040126367268876-127.0.0.1:52669_solr-n_0000000007, 98040126367268875-127.0.0.1:52664_solr-n_0000000010]
[junit4] 2> 2667992 INFO (OverseerStateUpdate-98040126367268873-127.0.0.1:52659_solr-n_0000000007) [ ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98040126367268873-127.0.0.1:52659_solr-n_0000000007"} 127.0.0.1:52659_solr
[junit4] 2> 2667993 INFO (OverseerStateUpdate-98040126367268873-127.0.0.1:52659_solr-n_0000000007) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52659_solr
[junit4] 2> 2667995 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2667997 INFO (OverseerExitThread) [ ] o.a.s.c.Overseer Overseer (id=98040126367268873-127.0.0.1:52659_solr-n_0000000007) closing
[junit4] 2> 2667998 INFO (zkCallback-3671-thread-1) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:52669_solr
[junit4] 2> 2667998 WARN (zkCallback-3671-thread-2) [ ] o.a.s.c.LeaderElector Our node is no longer in line to be leader
[junit4] 2> 2667999 INFO (zkCallback-3671-thread-1) [ ] o.a.s.c.Overseer Overseer (id=98040126367268876-127.0.0.1:52669_solr-n_0000000007) starting
[junit4] 2> 2682993 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[302BADA830062B5E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOverseerRole
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerRolesTest -Dtests.method=testOverseerRole -Dtests.seed=302BADA830062B5E -Dtests.slow=true -Dtests.locale=agq -Dtests.timezone=America/Thule -Dtests.asserts=true -Dtests.file.encoding=Cp1252
[junit4] FAILURE 15.6s J1 | OverseerRolesTest.testOverseerRole <<<
[junit4] > Throwable #1: java.lang.AssertionError: Timed out waiting for overseer state change
[junit4] > at __randomizedtesting.SeedInfo.seed([302BADA830062B5E:D1E0503C0BB51D8F]:0)
[junit4] > at org.apache.solr.cloud.OverseerRolesTest.waitForNewOverseer(OverseerRolesTest.java:62)
[junit4] > at org.apache.solr.cloud.OverseerRolesTest.testOverseerRole(OverseerRolesTest.java:140)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:563)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 2682997 INFO (jetty-closer-3650-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@30b95dfc{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2682997 INFO (jetty-closer-3650-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@37356b89{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2682998 INFO (jetty-closer-3650-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=718861717
[junit4] 2> 2682997 INFO (jetty-closer-3650-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@66f02ecf{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2682998 INFO (jetty-closer-3650-thread-3) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=709631477
[junit4] 2> 2682998 INFO (jetty-closer-3650-thread-4) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1505787302
[junit4] 2> 2682998 INFO (jetty-closer-3650-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2682998 INFO (jetty-closer-3650-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2682998 INFO (jetty-closer-3650-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2683008 INFO (jetty-closer-3650-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2683009 INFO (jetty-closer-3650-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2683009 INFO (jetty-closer-3650-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2683018 INFO (jetty-closer-3650-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2683018 INFO (jetty-closer-3650-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2683018 INFO (jetty-closer-3650-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2683020 INFO (jetty-closer-3650-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2683020 INFO (jetty-closer-3650-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2683020 INFO (jetty-closer-3650-thread-4) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2683020 INFO (jetty-closer-3650-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2683020 INFO (jetty-closer-3650-thread-2) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2683020 INFO (jetty-closer-3650-thread-3) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2683020 INFO (jetty-closer-3650-thread-3) [ ] o.a.s.c.Overseer Overseer (id=98040126367268876-127.0.0.1:52669_solr-n_0000000007) closing
[junit4] 2> 2683023 INFO (OverseerStateUpdate-98040126367268876-127.0.0.1:52669_solr-n_0000000007) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:52669_solr
[junit4] 2> 2683024 WARN (zkCallback-3668-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2683024 INFO (jetty-closer-3650-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6e4b9f4c{/solr,null,UNAVAILABLE}
[junit4] 2> 2684524 WARN (zkCallback-3670-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2684524 INFO (jetty-closer-3650-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4f4be3c8{/solr,null,UNAVAILABLE}
[junit4] 2> 2684526 WARN (zkCallback-3671-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2684526 INFO (jetty-closer-3650-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4abf494b{/solr,null,UNAVAILABLE}
[junit4] 2> 2684529 ERROR (SUITE-OverseerRolesTest-seed#[302BADA830062B5E]-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> 2684529 INFO (SUITE-OverseerRolesTest-seed#[302BADA830062B5E]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52650 52650
[junit4] 2> 2684530 INFO (Thread-4163) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:52650 52650
[junit4] 2> 2684531 WARN (Thread-4163) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterprops.json
[junit4] 2> 4 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 2 /solr/overseer_elect/election/98040126367268873-127.0.0.1:52659_solr-n_0000000007
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 9 /solr/overseer/queue-work
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 5 /solr/collections
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_302BADA830062B5E-001
[junit4] 2> May 28, 2017 12:13:45 PM 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=1286, maxMBSortInHeap=7.096240422891908, sim=RandomSimilarity(queryNorm=true): {}, locale=agq, timezone=America/Thule
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9-ea (64-bit)/cpus=3,threads=1,free=84120712,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestCopyFieldCollectionResource, ShardRoutingCustomTest, MinimalSchemaTest, SSLMigrationTest, TestQuerySenderListener, SystemInfoHandlerTest, TestExactStatsCache, TestLeaderElectionZkExpiry, UtilsToolTest, TestLRUCache, TermVectorComponentDistributedTest, UpdateParamsTest, TestDistribIDF, SpellCheckCollatorTest, HdfsDirectoryFactoryTest, DistributedVersionInfoTest, TestStandardQParsers, TestSimpleTextCodec, TestCustomStream, SuggesterWFSTTest, TestLMJelinekMercerSimilarityFactory, ManagedSchemaRoundRobinCloudTest, TestOmitPositions, TestSSLRandomization, TestLegacyNumericRangeQueryBuilder, BasicDistributedZk2Test, TestSolrCLIRunExample, CacheHeaderTest, DistributedExpandComponentTest, DistributedFacetPivotWhiteBoxTest, TestDFRSimilarityFactory, SuggesterTSTTest, DefaultValueUpdateProcessorTest, TestConfigSetImmutable, BlockJoinFacetRandomTest, TemplateUpdateProcessorTest, TestCustomSort, TestClassicSimilarityFactory, DistributedFacetPivotLargeTest, MetricUtilsTest, TestSolrQueryResponse, TestCustomDocTransformer, LukeRequestHandlerTest, PreAnalyzedFieldTest, TestUniqueKeyFieldResource, TestFoldingMultitermQuery, DistribCursorPagingTest, BasicAuthIntegrationTest, SubstringBytesRefFilterTest, GraphQueryTest, TestPullReplica, SpatialHeatmapFacetsTest, TestMiniSolrCloudCluster, TestFuzzyAnalyzedSuggestions, CleanupOldIndexTest, TestRangeQuery, TestFieldTypeResource, AnalysisErrorHandlingTest, StatsComponentTest, TestPKIAuthenticationPlugin, CurrencyFieldOpenExchangeTest, TestRawResponseWriter, TestSchemalessBufferedUpdates, ReplicaListTransformerTest, TestUnifiedSolrHighlighter, CollectionsAPISolrJTest, HdfsThreadLeakTest, ZkStateReaderTest, SliceStateTest, SuggestComponentTest, HdfsRecoverLeaseTest, TestFieldCacheSortRandom, TestPayloadCheckQParserPlugin, TestInitParams, DebugComponentTest, LeaderInitiatedRecoveryOnShardRestartTest, BigEndianAscendingWordDeserializerTest, TestAddFieldRealTimeGet, TestTolerantUpdateProcessorRandomCloud, TestIndexSearcher, TestAnalyzedSuggestions, JavabinLoaderTest, ChaosMonkeySafeLeaderTest, DistanceUnitsTest, AliasIntegrationTest, TestConfigSetsAPI, TestCloudDeleteByQuery, CoreMergeIndexesAdminHandlerTest, ShardSplitTest, URLClassifyProcessorTest, TestTrieFacet, FullHLLTest, TestRealTimeGet, DistributedFacetPivotSmallAdvancedTest, HdfsUnloadDistributedZkTest, BadCopyFieldTest, ShowFileRequestHandlerTest, TestFiltering, ConjunctionSolrSpellCheckerTest, TestExpandComponent, SolrCoreCheckLockOnStartupTest, RequestHandlersTest, TestRebalanceLeaders, TestBackupRepositoryFactory, SolrXmlInZkTest, TestStressInPlaceUpdates, AsyncCallRequestStatusResponseTest, DeleteInactiveReplicaTest, TestFileDictionaryLookup, NotRequiredUniqueKeyTest, SortByFunctionTest, MultiThreadedOCPTest, TestConfigSets, TestLuceneMatchVersion, TestImpersonationWithHadoopAuth, TestLocalFSCloudBackupRestore, TestJsonFacets, DOMUtilTest, ClassificationUpdateProcessorIntegrationTest, TestSolrQueryParser, AnalyticsQueryTest, TestConfig, TestConfigSetsAPIZkFailure, TestPointFields, TestWriterPerf, CoreAdminCreateDiscoverTest, CollectionReloadTest, BlockDirectoryTest, TestRecovery, DistributedQueryComponentOptimizationTest, TestQueryTypes, CdcrBootstrapTest, TestIndexingPerformance, StatsReloadRaceTest, SolrIndexMetricsTest, TestFilteredDocIdSet, TolerantUpdateProcessorTest, NoCacheHeaderTest, DocValuesMissingTest, TestImplicitCoreProperties, TestJoin, CreateCollectionCleanupTest, ConfigSetsAPITest, TestStressRecovery, TestTrie, DirectoryFactoryTest, TestInPlaceUpdatesDistrib, TestSQLHandler, TestNoOpRegenerator, ChangedSchemaMergeTest, TestBadConfig, TestSystemCollAutoCreate, RankQueryTest, TestCloudPseudoReturnFields, TestReqParamsAPI, TestReloadAndDeleteDocs, JsonLoaderTest, TestXmlQParserPlugin, TestUninvertingReader, TestValueSourceCache, TestClusterProperties, CSVRequestHandlerTest, TestExceedMaxTermLength, ConfigureRecoveryStrategyTest, SuggesterTest, SpatialRPTFieldTypeTest, TestHashQParserPlugin, FullSolrCloudDistribCmdsTest, FacetPivotSmallTest, MetricsHandlerTest, HttpSolrCallGetCoreTest, IndexSchemaTest, TestSearchPerf, HLLUtilTest, AtomicUpdatesTest, RestartWhileUpdatingTest, TestTlogReplica, AutoCommitTest, TestHashPartitioner, TestStressReorder, TestReload, TestCoreContainer, SimpleFacetsTest, SolrCoreTest, SolrCmdDistributorTest, BasicFunctionalityTest, SoftAutoCommitTest, DistributedQueryElevationComponentTest, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, SuggesterFSTTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, FieldMutatingUpdateProcessorTest, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DistanceFunctionTest, TestQueryUtils, TestReversedWildcardFilterFactory, PathHierarchyTokenizerFactoryTest, FieldAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, TestJmxIntegration, TestCSVResponseWriter, UniqFieldsUpdateProcessorFactoryTest, PingRequestHandlerTest, HighlighterConfigTest, SOLR749Test, AlternateDirectoryTest, TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, BadComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, SampleTest, TestDocSet, TestBinaryField, ExternalFileFieldSortTest, SpellPossibilityIteratorTest, TestCharFilters, TestCodecSupport, TestLMDirichletSimilarityFactory, TimeZoneUtilsTest, TestFastOutputStream, PluginInfoTest, ChaosMonkeyNothingIsSafeTest, ActionThrottleTest, CdcrReplicationDistributedZkTest, CdcrRequestHandlerTest, ChaosMonkeySafeLeaderWithPullReplicasTest, HttpPartitionTest, MissingSegmentRecoveryTest, MoveReplicaTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest]
[junit4] Completed [524/715 (1!)] on J1 in 18.25s, 1 test, 1 failure <<< FAILURES!
[...truncated 36890 lines...]