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/07/11 12:47:20 UTC
[JENKINS] Lucene-Solr-7.x-Windows (64bit/jdk1.8.0_131) - Build # 30
- Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Windows/30/
Java: 64bit/jdk1.8.0_131 -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([121D6F1CECFA9990:F3D69288D749AF41]: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 sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 12550 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerRolesTest
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_121D6F1CECFA9990-001\init-core-data-001
[junit4] 2> 2395178 INFO (SUITE-OverseerRolesTest-seed#[121D6F1CECFA9990]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2395182 INFO (SUITE-OverseerRolesTest-seed#[121D6F1CECFA9990]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 2395184 INFO (SUITE-OverseerRolesTest-seed#[121D6F1CECFA9990]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_121D6F1CECFA9990-001\tempDir-001
[junit4] 2> 2395184 INFO (SUITE-OverseerRolesTest-seed#[121D6F1CECFA9990]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2395184 INFO (Thread-4371) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 2395184 INFO (Thread-4371) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2395189 ERROR (Thread-4371) [ ] 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> 2395284 INFO (SUITE-OverseerRolesTest-seed#[121D6F1CECFA9990]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:61341
[junit4] 2> 2395290 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 0x15d31931e900000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2395295 INFO (jetty-launcher-3950-thread-4) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2395295 INFO (jetty-launcher-3950-thread-3) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2395295 INFO (jetty-launcher-3950-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2395295 INFO (jetty-launcher-3950-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028
[junit4] 2> 2395296 INFO (jetty-launcher-3950-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2173c4bf{/solr,null,AVAILABLE}
[junit4] 2> 2395296 INFO (jetty-launcher-3950-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@28833ee1{/solr,null,AVAILABLE}
[junit4] 2> 2395296 INFO (jetty-launcher-3950-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@10eedf1e{/solr,null,AVAILABLE}
[junit4] 2> 2395296 INFO (jetty-launcher-3950-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@38135902{HTTP/1.1,[http/1.1]}{127.0.0.1:61345}
[junit4] 2> 2395296 INFO (jetty-launcher-3950-thread-4) [ ] o.e.j.s.Server Started @2400352ms
[junit4] 2> 2395296 INFO (jetty-launcher-3950-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61345}
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@89fab14{HTTP/1.1,[http/1.1]}{127.0.0.1:61348}
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@d71d5c7{HTTP/1.1,[http/1.1]}{127.0.0.1:61349}
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-1) [ ] o.e.j.s.Server Started @2400352ms
[junit4] 2> 2395297 ERROR (jetty-launcher-3950-thread-4) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@610efed1{/solr,null,AVAILABLE}
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-11T12:15:11.612Z
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-2) [ ] o.e.j.s.Server Started @2400352ms
[junit4] 2> 2395297 INFO (jetty-launcher-3950-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61349}
[junit4] 2> 2395303 ERROR (jetty-launcher-3950-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@439240d2{HTTP/1.1,[http/1.1]}{127.0.0.1:61354}
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-3) [ ] o.e.j.s.Server Started @2400359ms
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61354}
[junit4] 2> 2395303 INFO (jetty-launcher-3950-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=61348}
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-11T12:15:11.619Z
[junit4] 2> 2395304 ERROR (jetty-launcher-3950-thread-3) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2395304 INFO (jetty-launcher-3950-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 2395304 ERROR (jetty-launcher-3950-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2395305 INFO (jetty-launcher-3950-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-11T12:15:11.620Z
[junit4] 2> 2395305 INFO (jetty-launcher-3950-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.1.0
[junit4] 2> 2395305 INFO (jetty-launcher-3950-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2395305 INFO (jetty-launcher-3950-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null, Default config dir: null
[junit4] 2> 2395305 INFO (jetty-launcher-3950-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-07-11T12:15:11.620Z
[junit4] 2> 2395307 INFO (jetty-launcher-3950-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2395308 INFO (jetty-launcher-3950-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2395308 INFO (jetty-launcher-3950-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2395310 INFO (jetty-launcher-3950-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2395344 INFO (jetty-launcher-3950-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61341/solr
[junit4] 2> 2395344 INFO (jetty-launcher-3950-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61341/solr
[junit4] 2> 2395345 INFO (jetty-launcher-3950-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61341/solr
[junit4] 2> 2395345 INFO (jetty-launcher-3950-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:61341/solr
[junit4] 2> 2395348 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 0x15d31931e900006, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2395502 INFO (jetty-launcher-3950-thread-4) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2395503 INFO (jetty-launcher-3950-thread-4) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61345_solr
[junit4] 2> 2395504 INFO (jetty-launcher-3950-thread-4) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer (id=98289274814726153-127.0.0.1:61345_solr-n_0000000000) starting
[junit4] 2> 2395512 INFO (jetty-launcher-3950-thread-4) [n:127.0.0.1:61345_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61345_solr
[junit4] 2> 2395520 INFO (zkCallback-3969-thread-1-processing-n:127.0.0.1:61345_solr) [n:127.0.0.1:61345_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2395552 INFO (jetty-launcher-3950-thread-1) [n:127.0.0.1:61349_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2395552 INFO (jetty-launcher-3950-thread-2) [n:127.0.0.1:61348_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2395552 INFO (jetty-launcher-3950-thread-3) [n:127.0.0.1:61354_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2395556 INFO (jetty-launcher-3950-thread-1) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2395556 INFO (jetty-launcher-3950-thread-2) [n:127.0.0.1:61348_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2395557 INFO (jetty-launcher-3950-thread-3) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 2395563 INFO (jetty-launcher-3950-thread-1) [n:127.0.0.1:61349_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61349_solr
[junit4] 2> 2395563 INFO (jetty-launcher-3950-thread-2) [n:127.0.0.1:61348_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61348_solr
[junit4] 2> 2395563 INFO (jetty-launcher-3950-thread-3) [n:127.0.0.1:61354_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61354_solr
[junit4] 2> 2395589 INFO (zkCallback-3969-thread-1-processing-n:127.0.0.1:61345_solr) [n:127.0.0.1:61345_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 2395590 INFO (zkCallback-3972-thread-1-processing-n:127.0.0.1:61349_solr) [n:127.0.0.1:61349_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 2395590 INFO (zkCallback-3971-thread-1-processing-n:127.0.0.1:61348_solr) [n:127.0.0.1:61348_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 2395609 INFO (zkCallback-3970-thread-1-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 2395641 INFO (jetty-launcher-3950-thread-4) [n:127.0.0.1:61345_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61345.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395643 INFO (jetty-launcher-3950-thread-4) [n:127.0.0.1:61345_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61345.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395643 INFO (jetty-launcher-3950-thread-4) [n:127.0.0.1:61345_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61345.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395644 INFO (jetty-launcher-3950-thread-4) [n:127.0.0.1:61345_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_121D6F1CECFA9990-001\tempDir-001\node1\.
[junit4] 2> 2395651 INFO (jetty-launcher-3950-thread-1) [n:127.0.0.1:61349_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61349.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395667 INFO (jetty-launcher-3950-thread-1) [n:127.0.0.1:61349_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61349.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395667 INFO (jetty-launcher-3950-thread-1) [n:127.0.0.1:61349_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61349.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395668 INFO (jetty-launcher-3950-thread-1) [n:127.0.0.1:61349_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_121D6F1CECFA9990-001\tempDir-001\node3\.
[junit4] 2> 2395710 INFO (jetty-launcher-3950-thread-3) [n:127.0.0.1:61354_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61354.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395710 INFO (jetty-launcher-3950-thread-2) [n:127.0.0.1:61348_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61348.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395714 INFO (jetty-launcher-3950-thread-2) [n:127.0.0.1:61348_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61348.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395714 INFO (jetty-launcher-3950-thread-2) [n:127.0.0.1:61348_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61348.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395715 INFO (jetty-launcher-3950-thread-2) [n:127.0.0.1:61348_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_121D6F1CECFA9990-001\tempDir-001\node2\.
[junit4] 2> 2395725 INFO (jetty-launcher-3950-thread-3) [n:127.0.0.1:61354_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61354.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395726 INFO (jetty-launcher-3950-thread-3) [n:127.0.0.1:61354_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_61354.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63c76f7c
[junit4] 2> 2395727 INFO (jetty-launcher-3950-thread-3) [n:127.0.0.1:61354_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_121D6F1CECFA9990-001\tempDir-001\node4\.
[junit4] 2> 2395758 INFO (SUITE-OverseerRolesTest-seed#[121D6F1CECFA9990]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 2395759 INFO (SUITE-OverseerRolesTest-seed#[121D6F1CECFA9990]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:61341/solr ready
[junit4] 2> 2395780 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOverseerRole
[junit4] 2> 2395781 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:61345_solr
[junit4] 2> 2395781 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 2395782 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest ### Setting overseer designate 127.0.0.1:61348_solr
[junit4] 2> 2395789 INFO (qtp1478135284-21778) [n:127.0.0.1:61349_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:61348_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2395800 INFO (OverseerThreadFactory-8678-thread-1-processing-n:127.0.0.1:61345_solr) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerRoleCmd Overseer role added to 127.0.0.1:61348_solr
[junit4] 2> 2395803 INFO (qtp1478135284-21778) [n:127.0.0.1:61349_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:61348_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=13
[junit4] 2> 2395805 INFO (Thread-4417) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98289274814726153-127.0.0.1:61345_solr-n_0000000000 overseer designates are [127.0.0.1:61348_solr]
[junit4] 2> 2395805 INFO (Thread-4417) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98289274814726153-127.0.0.1:61345_solr-n_0000000000, 98289274814726156-127.0.0.1:61349_solr-n_0000000001, 98289274814726154-127.0.0.1:61348_solr-n_0000000002, 98289274814726155-127.0.0.1:61354_solr-n_0000000003]
[junit4] 2> 2395806 INFO (Thread-4417) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerNodePrioritizer asking node 98289274814726154-127.0.0.1:61348_solr-n_0000000002 to come join election at head
[junit4] 2> 2395807 INFO (qtp1625128650-21770) [n:127.0.0.1:61348_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=98289274814726154-127.0.0.1:61348_solr-n_0000000002&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2395811 INFO (Thread-4417) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerNodePrioritizer asking the old first in line 98289274814726156-127.0.0.1:61349_solr-n_0000000001 to rejoin election
[junit4] 2> 2395813 INFO (qtp1478135284-21779) [n:127.0.0.1:61349_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=98289274814726156-127.0.0.1:61349_solr-n_0000000001&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2395815 INFO (OverseerStateUpdate-98289274814726153-127.0.0.1:61345_solr-n_0000000000) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98289274814726153-127.0.0.1:61345_solr-n_0000000000"} 127.0.0.1:61345_solr
[junit4] 2> 2395815 INFO (OverseerStateUpdate-98289274814726153-127.0.0.1:61345_solr-n_0000000000) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61345_solr
[junit4] 2> 2395816 WARN (OverseerExitThread) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2395817 INFO (zkCallback-3972-thread-1-processing-n:127.0.0.1:61349_solr) [n:127.0.0.1:61349_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61349_solr
[junit4] 2> 2395818 INFO (zkCallback-3972-thread-1-processing-n:127.0.0.1:61349_solr) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer Overseer (id=98289274814726156-127.0.0.1:61349_solr-n_0000000001) starting
[junit4] 2> 2395822 INFO (OverseerExitThread) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer (id=98289274814726153-127.0.0.1:61345_solr-n_0000000000) closing
[junit4] 2> 2395852 INFO (OverseerCollectionConfigSetProcessor-98289274814726156-127.0.0.1:61349_solr-n_0000000001) [n:127.0.0.1:61349_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98289274814726156-127.0.0.1:61349_solr-n_0000000001 overseer designates are [127.0.0.1:61348_solr]
[junit4] 2> 2395856 INFO (OverseerCollectionConfigSetProcessor-98289274814726156-127.0.0.1:61349_solr-n_0000000001) [n:127.0.0.1:61349_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98289274814726156-127.0.0.1:61349_solr-n_0000000001, 98289274814726154-127.0.0.1:61348_solr-n_0000000002, 98289274814726155-127.0.0.1:61354_solr-n_0000000003, 98289274814726153-127.0.0.1:61345_solr-n_0000000004]
[junit4] 2> 2395860 INFO (OverseerStateUpdate-98289274814726156-127.0.0.1:61349_solr-n_0000000001) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98289274814726156-127.0.0.1:61349_solr-n_0000000001"} 127.0.0.1:61349_solr
[junit4] 2> 2395861 INFO (OverseerStateUpdate-98289274814726156-127.0.0.1:61349_solr-n_0000000001) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61349_solr
[junit4] 2> 2395900 WARN (OverseerExitThread) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2395903 INFO (zkCallback-3971-thread-1-processing-n:127.0.0.1:61348_solr) [n:127.0.0.1:61348_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61348_solr
[junit4] 2> 2395905 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest ### Adding another overseer designate 127.0.0.1:61349_solr
[junit4] 2> 2395905 INFO (qtp1478135284-21779) [n:127.0.0.1:61349_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:61349_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2395906 INFO (zkCallback-3971-thread-1-processing-n:127.0.0.1:61348_solr) [n:127.0.0.1:61348_solr ] o.a.s.c.Overseer Overseer (id=98289274814726154-127.0.0.1:61348_solr-n_0000000002) starting
[junit4] 2> 2395909 INFO (OverseerExitThread) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer Overseer (id=98289274814726156-127.0.0.1:61349_solr-n_0000000001) closing
[junit4] 2> 2395924 INFO (OverseerThreadFactory-8690-thread-1-processing-n:127.0.0.1:61348_solr) [n:127.0.0.1:61348_solr ] o.a.s.c.OverseerRoleCmd Overseer role added to 127.0.0.1:61349_solr
[junit4] 2> 2395926 INFO (qtp1478135284-21779) [n:127.0.0.1:61349_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:61349_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=20
[junit4] 2> 2395927 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:61348_solr
[junit4] 2> 2395927 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 2395927 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.ChaosMonkey monkey: stop jetty! 61348
[junit4] 2> 2395927 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=86504522
[junit4] 2> 2395928 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2395929 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2395930 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2395931 INFO (OverseerCollectionConfigSetProcessor-98289274814726154-127.0.0.1:61348_solr-n_0000000002) [n:127.0.0.1:61348_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2395931 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2395932 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2395932 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.Overseer Overseer (id=98289274814726154-127.0.0.1:61348_solr-n_0000000002) closing
[junit4] 2> 2395932 INFO (OverseerStateUpdate-98289274814726154-127.0.0.1:61348_solr-n_0000000002) [n:127.0.0.1:61348_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61348_solr
[junit4] 2> 2395936 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 0x15d31931e90000a, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2395938 INFO (zkCallback-3970-thread-1-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61354_solr
[junit4] 2> 2395938 INFO (zkCallback-3969-thread-1-processing-n:127.0.0.1:61345_solr) [n:127.0.0.1:61345_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2395944 INFO (zkCallback-3980-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2395945 ERROR (Thread-4418) [n:127.0.0.1:61348_solr ] o.a.s.c.OverseerRoleCmd Error in prioritizing Overseer
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /roles.json
[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.exists(ZooKeeper.java:1102)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:323)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$5.execute(SolrZkClient.java:320)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:320)
[junit4] 2> at org.apache.solr.cloud.OverseerNodePrioritizer.prioritizeOverseerNodes(OverseerNodePrioritizer.java:61)
[junit4] 2> at org.apache.solr.cloud.OverseerRoleCmd.lambda$call$0(OverseerRoleCmd.java:93)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 2395946 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@89fab14{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2395949 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@28833ee1{/solr,null,UNAVAILABLE}
[junit4] 2> 2395949 INFO (zkCallback-3972-thread-1-processing-n:127.0.0.1:61349_solr) [n:127.0.0.1:61349_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2395950 INFO (zkCallback-3970-thread-2-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 2395951 INFO (zkCallback-3970-thread-1-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer (id=98289274814726155-127.0.0.1:61354_solr-n_0000000003) starting
[junit4] 2> 2395979 INFO (OverseerCollectionConfigSetProcessor-98289274814726155-127.0.0.1:61354_solr-n_0000000003) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98289274814726155-127.0.0.1:61354_solr-n_0000000003 overseer designates are [127.0.0.1:61348_solr, 127.0.0.1:61349_solr]
[junit4] 2> 2395980 INFO (OverseerCollectionConfigSetProcessor-98289274814726155-127.0.0.1:61354_solr-n_0000000003) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98289274814726155-127.0.0.1:61354_solr-n_0000000003, 98289274814726153-127.0.0.1:61345_solr-n_0000000004, 98289274814726156-127.0.0.1:61349_solr-n_0000000005]
[junit4] 2> 2395980 INFO (OverseerCollectionConfigSetProcessor-98289274814726155-127.0.0.1:61354_solr-n_0000000003) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerNodePrioritizer asking node 98289274814726156-127.0.0.1:61349_solr-n_0000000005 to come join election at head
[junit4] 2> 2395983 INFO (qtp1478135284-21767) [n:127.0.0.1:61349_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=98289274814726156-127.0.0.1:61349_solr-n_0000000005&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2395983 INFO (OverseerCollectionConfigSetProcessor-98289274814726155-127.0.0.1:61354_solr-n_0000000003) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerNodePrioritizer asking the old first in line 98289274814726153-127.0.0.1:61345_solr-n_0000000004 to rejoin election
[junit4] 2> 2395985 INFO (qtp1484823938-21783) [n:127.0.0.1:61345_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=98289274814726153-127.0.0.1:61345_solr-n_0000000004&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2395988 INFO (OverseerStateUpdate-98289274814726155-127.0.0.1:61354_solr-n_0000000003) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98289274814726155-127.0.0.1:61354_solr-n_0000000003"} 127.0.0.1:61354_solr
[junit4] 2> 2395997 INFO (OverseerStateUpdate-98289274814726155-127.0.0.1:61354_solr-n_0000000003) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61354_solr
[junit4] 2> 2395998 WARN (OverseerExitThread) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2395999 INFO (OverseerExitThread) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer (id=98289274814726155-127.0.0.1:61354_solr-n_0000000003) closing
[junit4] 2> 2396000 INFO (zkCallback-3969-thread-1-processing-n:127.0.0.1:61345_solr) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61345_solr
[junit4] 2> 2396003 INFO (zkCallback-3969-thread-1-processing-n:127.0.0.1:61345_solr) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer (id=98289274814726153-127.0.0.1:61345_solr-n_0000000004) starting
[junit4] 2> 2396018 INFO (OverseerCollectionConfigSetProcessor-98289274814726153-127.0.0.1:61345_solr-n_0000000004) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98289274814726153-127.0.0.1:61345_solr-n_0000000004 overseer designates are [127.0.0.1:61348_solr, 127.0.0.1:61349_solr]
[junit4] 2> 2396024 INFO (OverseerCollectionConfigSetProcessor-98289274814726153-127.0.0.1:61345_solr-n_0000000004) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98289274814726153-127.0.0.1:61345_solr-n_0000000004, 98289274814726156-127.0.0.1:61349_solr-n_0000000005, 98289274814726155-127.0.0.1:61354_solr-n_0000000006]
[junit4] 2> 2396030 INFO (OverseerStateUpdate-98289274814726153-127.0.0.1:61345_solr-n_0000000004) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98289274814726153-127.0.0.1:61345_solr-n_0000000004"} 127.0.0.1:61345_solr
[junit4] 2> 2396031 INFO (OverseerStateUpdate-98289274814726153-127.0.0.1:61345_solr-n_0000000004) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61345_solr
[junit4] 2> 2396058 WARN (OverseerExitThread) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2396059 INFO (OverseerExitThread) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer (id=98289274814726153-127.0.0.1:61345_solr-n_0000000004) closing
[junit4] 2> 2396059 INFO (zkCallback-3972-thread-1-processing-n:127.0.0.1:61349_solr) [n:127.0.0.1:61349_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61349_solr
[junit4] 2> 2396061 INFO (zkCallback-3972-thread-1-processing-n:127.0.0.1:61349_solr) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer Overseer (id=98289274814726156-127.0.0.1:61349_solr-n_0000000005) starting
[junit4] 2> 2396154 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest ### Adding last overseer designate 127.0.0.1:61354_solr
[junit4] 2> 2396155 INFO (qtp1478135284-21767) [n:127.0.0.1:61349_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:61354_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2396161 INFO (OverseerThreadFactory-8697-thread-1-processing-n:127.0.0.1:61349_solr) [n:127.0.0.1:61349_solr ] o.a.s.c.OverseerRoleCmd Overseer role added to 127.0.0.1:61354_solr
[junit4] 2> 2396163 INFO (qtp1478135284-21767) [n:127.0.0.1:61349_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:61354_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=7
[junit4] 2> 2396163 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest Overseer: 127.0.0.1:61349_solr
[junit4] 2> 2396164 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest Election queue:
[junit4] 2> 2396166 INFO (qtp1478135284-21786) [n:127.0.0.1:61349_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :removerole with params node=127.0.0.1:61349_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2396168 INFO (OverseerCollectionConfigSetProcessor-98289274814726156-127.0.0.1:61349_solr-n_0000000005) [n:127.0.0.1:61349_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2396174 INFO (OverseerThreadFactory-8697-thread-2-processing-n:127.0.0.1:61349_solr) [n:127.0.0.1:61349_solr ] o.a.s.c.OverseerRoleCmd Overseer role removed from 127.0.0.1:61349_solr
[junit4] 2> 2396176 INFO (qtp1478135284-21786) [n:127.0.0.1:61349_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:61349_solr&role=overseer&action=REMOVEROLE&wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 2396178 INFO (Thread-4421) [n:127.0.0.1:61349_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98289274814726156-127.0.0.1:61349_solr-n_0000000005 overseer designates are [127.0.0.1:61348_solr, 127.0.0.1:61354_solr]
[junit4] 2> 2396179 INFO (Thread-4421) [n:127.0.0.1:61349_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98289274814726156-127.0.0.1:61349_solr-n_0000000005, 98289274814726155-127.0.0.1:61354_solr-n_0000000006, 98289274814726153-127.0.0.1:61345_solr-n_0000000007]
[junit4] 2> 2396180 INFO (OverseerStateUpdate-98289274814726156-127.0.0.1:61349_solr-n_0000000005) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98289274814726156-127.0.0.1:61349_solr-n_0000000005"} 127.0.0.1:61349_solr
[junit4] 2> 2396181 INFO (OverseerStateUpdate-98289274814726156-127.0.0.1:61349_solr-n_0000000005) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61349_solr
[junit4] 2> 2396181 WARN (OverseerExitThread) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2396182 INFO (OverseerExitThread) [n:127.0.0.1:61349_solr ] o.a.s.c.Overseer Overseer (id=98289274814726156-127.0.0.1:61349_solr-n_0000000005) closing
[junit4] 2> 2396183 INFO (zkCallback-3970-thread-1-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61354_solr
[junit4] 2> 2396185 INFO (zkCallback-3970-thread-1-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer (id=98289274814726155-127.0.0.1:61354_solr-n_0000000006) starting
[junit4] 2> 2396278 INFO (qtp1478135284-21785) [n:127.0.0.1:61349_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addrole with params node=127.0.0.1:61349_solr&role=overseer&action=ADDROLE&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 2396281 INFO (OverseerThreadFactory-8699-thread-1-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerRoleCmd Overseer role added to 127.0.0.1:61349_solr
[junit4] 2> 2396283 INFO (qtp1478135284-21785) [n:127.0.0.1:61349_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:61349_solr&role=overseer&action=ADDROLE&wt=javabin&version=2} status=0 QTime=5
[junit4] 2> 2396283 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.c.OverseerRolesTest ### Sending QUIT to overseer 127.0.0.1:61354_solr
[junit4] 2> 2396286 INFO (OverseerStateUpdate-98289274814726155-127.0.0.1:61354_solr-n_0000000006) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98289274814726155-127.0.0.1:61354_solr-n_0000000006"} 127.0.0.1:61354_solr
[junit4] 2> 2396287 INFO (OverseerStateUpdate-98289274814726155-127.0.0.1:61354_solr-n_0000000006) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61354_solr
[junit4] 2> 2396289 WARN (OverseerExitThread) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2396292 INFO (Thread-4422) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98289274814726155-127.0.0.1:61354_solr-n_0000000006 overseer designates are [127.0.0.1:61348_solr, 127.0.0.1:61354_solr, 127.0.0.1:61349_solr]
[junit4] 2> 2396293 INFO (Thread-4422) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98289274814726155-127.0.0.1:61354_solr-n_0000000006, 98289274814726153-127.0.0.1:61345_solr-n_0000000007, 98289274814726156-127.0.0.1:61349_solr-n_0000000008]
[junit4] 2> 2396293 INFO (Thread-4422) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerNodePrioritizer asking node 98289274814726155-127.0.0.1:61354_solr-n_0000000006 to come join election at head
[junit4] 2> 2396293 INFO (OverseerExitThread) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer (id=98289274814726155-127.0.0.1:61354_solr-n_0000000006) closing
[junit4] 2> 2396296 INFO (zkCallback-3969-thread-1-processing-n:127.0.0.1:61345_solr) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61345_solr
[junit4] 2> 2396298 INFO (zkCallback-3969-thread-1-processing-n:127.0.0.1:61345_solr) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer (id=98289274814726153-127.0.0.1:61345_solr-n_0000000007) starting
[junit4] 2> 2396299 INFO (qtp1735680157-21773) [n:127.0.0.1:61354_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoinAtHead&qt=/admin/cores&action=OVERSEEROP&electionNode=98289274814726155-127.0.0.1:61354_solr-n_0000000006&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 2396299 INFO (Thread-4422) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerNodePrioritizer asking the old first in line 98289274814726153-127.0.0.1:61345_solr-n_0000000007 to rejoin election
[junit4] 2> 2396299 INFO (qtp1484823938-21749) [n:127.0.0.1:61345_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={op=rejoin&qt=/admin/cores&action=OVERSEEROP&electionNode=98289274814726153-127.0.0.1:61345_solr-n_0000000007&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2396308 INFO (OverseerStateUpdate-98289274814726153-127.0.0.1:61345_solr-n_0000000007) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Quit command received {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98289274814726153-127.0.0.1:61345_solr-n_0000000007"} 127.0.0.1:61345_solr
[junit4] 2> 2396309 INFO (OverseerCollectionConfigSetProcessor-98289274814726153-127.0.0.1:61345_solr-n_0000000007) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerNodePrioritizer prioritizing overseer nodes at 98289274814726153-127.0.0.1:61345_solr-n_0000000007 overseer designates are [127.0.0.1:61348_solr, 127.0.0.1:61354_solr, 127.0.0.1:61349_solr]
[junit4] 2> 2396309 INFO (OverseerCollectionConfigSetProcessor-98289274814726153-127.0.0.1:61345_solr-n_0000000007) [n:127.0.0.1:61345_solr ] o.a.s.c.OverseerNodePrioritizer sorted nodes [98289274814726153-127.0.0.1:61345_solr-n_0000000007, 98289274814726155-127.0.0.1:61354_solr-n_0000000008, 98289274814726156-127.0.0.1:61349_solr-n_0000000008]
[junit4] 2> 2396311 INFO (OverseerStateUpdate-98289274814726153-127.0.0.1:61345_solr-n_0000000007) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61345_solr
[junit4] 2> 2396313 WARN (OverseerExitThread) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer I'm exiting, but I'm still the leader
[junit4] 2> 2396314 INFO (OverseerExitThread) [n:127.0.0.1:61345_solr ] o.a.s.c.Overseer Overseer (id=98289274814726153-127.0.0.1:61345_solr-n_0000000007) closing
[junit4] 2> 2396314 INFO (zkCallback-3970-thread-1-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:61354_solr
[junit4] 2> 2396315 INFO (zkCallback-3970-thread-1-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer (id=98289274814726155-127.0.0.1:61354_solr-n_0000000008) starting
[junit4] 2> 2396321 WARN (OverseerStateUpdate-98289274814726155-127.0.0.1:61354_solr-n_0000000008) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer received wrong QUIT message {
[junit4] 2> "operation":"quit",
[junit4] 2> "id":"98289274814726153-127.0.0.1:61345_solr-n_0000000007"}
[junit4] 2> 2411351 INFO (TEST-OverseerRolesTest.testOverseerRole-seed#[121D6F1CECFA9990]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOverseerRole
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerRolesTest -Dtests.method=testOverseerRole -Dtests.seed=121D6F1CECFA9990 -Dtests.slow=true -Dtests.locale=ru -Dtests.timezone=Asia/Thimphu -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[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([121D6F1CECFA9990:F3D69288D749AF41]: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.lang.Thread.run(Thread.java:748)
[junit4] 2> 2411355 INFO (jetty-closer-3951-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@38135902{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2411356 INFO (jetty-closer-3951-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1631390612
[junit4] 2> 2411356 INFO (jetty-closer-3951-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@d71d5c7{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2411357 INFO (jetty-closer-3951-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=383045178
[junit4] 2> 2411357 INFO (jetty-closer-3951-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2411357 INFO (jetty-closer-3951-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2411357 INFO (jetty-closer-3951-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@439240d2{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 2411358 INFO (jetty-closer-3951-thread-4) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=998859054
[junit4] 2> 2411358 INFO (jetty-closer-3951-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 2411368 INFO (jetty-closer-3951-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2411368 INFO (jetty-closer-3951-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2411369 INFO (jetty-closer-3951-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 2411377 INFO (jetty-closer-3951-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2411377 INFO (jetty-closer-3951-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2411377 INFO (jetty-closer-3951-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 2411378 INFO (jetty-closer-3951-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2411378 INFO (jetty-closer-3951-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2411378 INFO (jetty-closer-3951-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 2411379 INFO (jetty-closer-3951-thread-4) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2411379 INFO (jetty-closer-3951-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2411379 INFO (jetty-closer-3951-thread-4) [ ] o.a.s.c.Overseer Overseer (id=98289274814726155-127.0.0.1:61354_solr-n_0000000008) closing
[junit4] 2> 2411379 INFO (jetty-closer-3951-thread-2) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 2411380 INFO (OverseerStateUpdate-98289274814726155-127.0.0.1:61354_solr-n_0000000008) [n:127.0.0.1:61354_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:61354_solr
[junit4] 2> 2412882 WARN (zkCallback-3970-thread-1-processing-n:127.0.0.1:61354_solr) [n:127.0.0.1:61354_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2412882 WARN (zkCallback-3972-thread-1-processing-n:127.0.0.1:61349_solr) [n:127.0.0.1:61349_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2412882 INFO (jetty-closer-3951-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@610efed1{/solr,null,UNAVAILABLE}
[junit4] 2> 2412882 WARN (zkCallback-3969-thread-1-processing-n:127.0.0.1:61345_solr) [n:127.0.0.1:61345_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes]
[junit4] 2> 2412882 INFO (jetty-closer-3951-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@10eedf1e{/solr,null,UNAVAILABLE}
[junit4] 2> 2412883 INFO (jetty-closer-3951-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2173c4bf{/solr,null,UNAVAILABLE}
[junit4] 2> 2412885 ERROR (SUITE-OverseerRolesTest-seed#[121D6F1CECFA9990]-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> 2412885 INFO (SUITE-OverseerRolesTest-seed#[121D6F1CECFA9990]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:61341 61341
[junit4] 2> 2412886 INFO (Thread-4371) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:61341 61341
[junit4] 2> 2412887 WARN (Thread-4371) [ ] 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/98289274814726153-127.0.0.1:61345_solr-n_0000000007
[junit4] 2> 2 /solr/overseer_elect/election/98289274814726156-127.0.0.1:61349_solr-n_0000000008
[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-7.x-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerRolesTest_121D6F1CECFA9990-001
[junit4] 2> Jul 11, 2017 12:15:29 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=1646, maxMBSortInHeap=7.740889159894866, sim=RandomSimilarity(queryNorm=false): {}, locale=ru, timezone=Asia/Thimphu
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_131 (64-bit)/cpus=3,threads=1,free=173695400,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [TestDistributedMissingSort, TestSmileRequest, QueryResultKeyTest, CachingDirectoryFactoryTest, TestNumericTerms32, TestPayloadCheckQParserPlugin, TestDocumentBuilder, TestBadConfig, HLLUtilTest, CollectionsAPIAsyncDistributedZkTest, TestSolrQueryParser, TestFileDictionaryLookup, TestCloudManagedSchema, TestSQLHandler, TestCoreDiscovery, OpenExchangeRatesOrgProviderTest, TestConfig, AddSchemaFieldsUpdateProcessorFactoryTest, TestAnalyzeInfixSuggestions, DistributedFacetPivotSmallTest, BasicFunctionalityTest, HdfsWriteToMultipleCollectionsTest, TestHdfsBackupRestoreCore, TestJettySolrRunner, SpatialRPTFieldTypeTest, TestFieldCacheSortRandom, LeaderElectionIntegrationTest, TestNRTOpen, SOLR749Test, SynonymTokenizerTest, DistributedMLTComponentTest, TestPKIAuthenticationPlugin, CacheHeaderTest, TestSlowCompositeReaderWrapper, TestSha256AuthenticationProvider, TestConfigSetsAPI, HttpSolrCallGetCoreTest, DistributedFacetPivotLongTailTest, SolrShardReporterTest, LukeRequestHandlerTest, TestLegacyNumericUtils, TestBlobHandler, HighlighterMaxOffsetTest, UpdateLogTest, TestConfigSetsAPIZkFailure, SuggestComponentContextFilterQueryTest, StatsComponentTest, SpellingQueryConverterTest, TestCustomDocTransformer, TestExceedMaxTermLength, LeaderFailoverAfterPartitionTest, SolrCoreCheckLockOnStartupTest, TestQueryWrapperFilter, ZkStateWriterTest, TestReload, TestMiniSolrCloudCluster, AnalysisErrorHandlingTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestMergePolicyConfig, TestCloudSchemaless, HdfsRecoveryZkTest, SolrCmdDistributorTest, TestMacros, SuggesterTSTTest, TestCorePropertiesReload, PluginInfoTest, SignatureUpdateProcessorFactoryTest, TestManagedSchemaAPI, TestImpersonationWithHadoopAuth, TestSolrCoreParser, SharedFSAutoReplicaFailoverUtilsTest, TestFilteredDocIdSet, TestRemoteStreaming, PreAnalyzedFieldTest, InfoHandlerTest, CustomCollectionTest, BaseCdcrDistributedZkTest, TestObjectReleaseTracker, RankQueryTest, TestDynamicFieldResource, CdcrRequestHandlerTest, PreAnalyzedFieldManagedSchemaCloudTest, TestLMJelinekMercerSimilarityFactory, FieldMutatingUpdateProcessorTest, UpdateRequestProcessorFactoryTest, ShufflingReplicaListTransformerTest, OverseerTaskQueueTest, TestFieldCacheWithThreads, BadCopyFieldTest, TestSchemaVersionResource, PropertiesRequestHandlerTest, SolrMetricManagerTest, TestTrie, DistributedIntervalFacetingTest, TestAnalyzedSuggestions, TestLocalFSCloudBackupRestore, ChaosMonkeySafeLeaderTest, CdcrReplicationDistributedZkTest, MoreLikeThisHandlerTest, HdfsTlogReplayBufferedWhileIndexingTest, SpellPossibilityIteratorTest, SolrMetricsIntegrationTest, UtilsToolTest, TestSolrIndexConfig, ChangedSchemaMergeTest, DeleteReplicaTest, TestLegacyNumericRangeQueryBuilder, MetricsConfigTest, ConnectionManagerTest, UpdateParamsTest, CoreAdminRequestStatusTest, LeaderInitiatedRecoveryOnCommitTest, TestMissingGroups, TestRealTimeGet, FileBasedSpellCheckerTest, SmileWriterTest, TlogReplayBufferedWhileIndexingTest, TestFiltering, TestManagedSynonymFilterFactory, RecoveryAfterSoftCommitTest, TestPerFieldSimilarity, TestJoin, TestConfigSetProperties, TestGeoJSONResponseWriter, TestDocTermOrds, GraphQueryTest, TestXmlQParserPlugin, DirectSolrSpellCheckerTest, DistributedFacetExistsSmallTest, TestFieldResource, SolrCLIZkUtilsTest, QueryEqualityTest, ActionThrottleTest, StatelessScriptUpdateProcessorFactoryTest, BlockJoinFacetRandomTest, TestFieldTypeResource, FullSolrCloudDistribCmdsTest, CollectionsAPIDistributedZkTest, TestZkChroot, CollectionStateFormat2Test, OpenCloseCoreStressTest, TestSolrDeletionPolicy1, RestartWhileUpdatingTest, TestMiniSolrCloudClusterSSL, TestSolrJ, DocValuesMultiTest, TestUninvertingReader, TestClassicSimilarityFactory, SolrGraphiteReporterTest, TestSolrCloudWithKerberosAlt, TestRecoveryHdfs, TestSolr4Spatial, LargeFieldTest, TestInfoStreamLogging, HdfsNNFailoverTest, TestPrepRecovery, TestInPlaceUpdatesDistrib, TestSubQueryTransformerDistrib, TolerantUpdateProcessorTest, TestClusterStateMutator, TermVectorComponentTest, MetricUtilsTest, TestFastWriter, IndexSchemaRuntimeFieldTest, TestUniqueKeyFieldResource, CopyFieldTest, TestCSVLoader, TestDynamicLoading, ZkNodePropsTest, TestMultiValuedNumericRangeQuery, DateRangeFieldTest, DistributedQueryComponentCustomSortTest, ExitableDirectoryReaderTest, ConvertedLegacyTest, HttpPartitionTest, TestCopyFieldCollectionResource, TestNumericRangeQuery64, BasicDistributedZk2Test, SyncSliceTest, ShardRoutingTest, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, TestRecovery, TermVectorComponentDistributedTest, DistributedTermsComponentTest, SpellCheckComponentTest, TestSort, DirectUpdateHandlerTest, TestIndexSearcher, SoftAutoCommitTest, DistributedQueryElevationComponentTest, TestStressLucene, SuggesterWFSTTest, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, DirectUpdateHandlerOptimizeTest, DistanceFunctionTest, IndexBasedSpellCheckerTest, TestQueryUtils, TestQueryTypes, PrimitiveFieldTypeTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, RequiredFieldsTest, LoggingHandlerTest, TestCollationField, ReturnFieldsTest, JsonLoaderTest, TestComponentsName, SearchHandlerTest, HighlighterConfigTest, TestQuerySenderNoQuery, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestSolrDeletionPolicy2, SampleTest, TestDocSet, TestSearchPerf, NumericFieldsTest, TestElisionMultitermQuery, OutputWriterTest, ExternalFileFieldSortTest, TestSolrCoreProperties, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestXIncludeConfig, TestDFRSimilarityFactory, TestIBSimilarityFactory, TimeZoneUtilsTest, ResourceLoaderTest, TestFastLRUCache, TestSuggestSpellingConverter, DateFieldTest, RAMDirectoryFactoryTest, SliceStateTest, FileUtilsTest, TestRTGBase, TestCrossCoreJoin, TestSimpleTrackingShardHandler, AssignTest, CdcrBootstrapTest, ChaosMonkeySafeLeaderWithPullReplicasTest, CleanupOldIndexTest, CloudExitableDirectoryReaderTest, CollectionsAPISolrJTest, DistribDocExpirationUpdateProcessorTest, DistributedVersionInfoTest, MissingSegmentRecoveryTest, MoveReplicaHDFSTest, MoveReplicaTest, NodeMutatorTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest]
[junit4] Completed [526/727 (1!)] on J1 in 18.73s, 1 test, 1 failure <<< FAILURES!
[...truncated 44072 lines...]