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 2018/03/08 08:09:05 UTC
[JENKINS-EA] Lucene-Solr-7.x-Linux (64bit/jdk-10-ea+43) - Build #
1486 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1486/
Java: 64bit/jdk-10-ea+43 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.TestCloudConsistency.testOutOfSyncReplicasCannotBecomeLeader
Error Message:
Doc with id=4 not found in http://127.0.0.1:42381/solr/outOfSyncReplicasCannotBecomeLeader-false due to: Path not found: /id; rsp={doc=null}
Stack Trace:
java.lang.AssertionError: Doc with id=4 not found in http://127.0.0.1:42381/solr/outOfSyncReplicasCannotBecomeLeader-false due to: Path not found: /id; rsp={doc=null}
at __randomizedtesting.SeedInfo.seed([9ABEE8FF6B3AC1A8:E455C8EFA85DCE92]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.TestCloudConsistency.assertDocExists(TestCloudConsistency.java:250)
at org.apache.solr.cloud.TestCloudConsistency.assertDocsExistInAllReplicas(TestCloudConsistency.java:234)
at org.apache.solr.cloud.TestCloudConsistency.testOutOfSyncReplicasCannotBecomeLeader(TestCloudConsistency.java:127)
at org.apache.solr.cloud.TestCloudConsistency.testOutOfSyncReplicasCannotBecomeLeader(TestCloudConsistency.java:90)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 13390 lines...]
[junit4] Suite: org.apache.solr.cloud.TestCloudConsistency
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/init-core-data-001
[junit4] 2> 930427 WARN (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
[junit4] 2> 930427 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 930428 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 930428 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 930428 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001
[junit4] 2> 930428 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 930428 INFO (Thread-2256) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 930428 INFO (Thread-2256) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 930432 ERROR (Thread-2256) [ ] 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> 930528 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:37701
[junit4] 2> 930530 INFO (zkConnectionManagerCallback-1670-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930532 INFO (jetty-launcher-1667-thread-1) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:27:37+09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 930532 INFO (jetty-launcher-1667-thread-3) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:27:37+09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 930532 INFO (jetty-launcher-1667-thread-4) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:27:37+09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 930532 INFO (jetty-launcher-1667-thread-2) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:27:37+09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-2) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-4) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7dff2ce4{/solr,null,AVAILABLE}
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f5977a3{/solr,null,AVAILABLE}
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-1) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-3) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7ffc1e6f{/solr,null,AVAILABLE}
[junit4] 2> 930533 INFO (jetty-launcher-1667-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@39ea1e6e{/solr,null,AVAILABLE}
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@649d4001{HTTP/1.1,[http/1.1]}{127.0.0.1:46617}
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@59d79b34{HTTP/1.1,[http/1.1]}{127.0.0.1:34097}
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-1) [ ] o.e.j.s.Server Started @932464ms
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-2) [ ] o.e.j.s.Server Started @932464ms
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1b6df8f8{HTTP/1.1,[http/1.1]}{127.0.0.1:46585}
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34097}
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-4) [ ] o.e.j.s.Server Started @932464ms
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6d98fe42{HTTP/1.1,[http/1.1]}{127.0.0.1:45029}
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46585}
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-3) [ ] o.e.j.s.Server Started @932465ms
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46617}
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45029}
[junit4] 2> 930535 ERROR (jetty-launcher-1667-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 930535 ERROR (jetty-launcher-1667-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 930535 ERROR (jetty-launcher-1667-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-08T07:29:44.761677Z
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 930535 ERROR (jetty-launcher-1667-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-08T07:29:44.761918Z
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 930535 INFO (jetty-launcher-1667-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-08T07:29:44.761979Z
[junit4] 2> 930536 INFO (jetty-launcher-1667-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-08T07:29:44.762004Z
[junit4] 2> 930536 INFO (zkConnectionManagerCallback-1672-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930537 INFO (zkConnectionManagerCallback-1674-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930537 INFO (zkConnectionManagerCallback-1678-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930537 INFO (zkConnectionManagerCallback-1676-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930537 INFO (jetty-launcher-1667-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 930537 INFO (jetty-launcher-1667-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 930537 INFO (jetty-launcher-1667-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 930537 INFO (jetty-launcher-1667-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 930552 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10025a860860001, likely client has closed socket
[junit4] 2> 930553 INFO (jetty-launcher-1667-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37701/solr
[junit4] 2> 930553 INFO (jetty-launcher-1667-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37701/solr
[junit4] 2> 930554 INFO (zkConnectionManagerCallback-1684-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930554 INFO (zkConnectionManagerCallback-1687-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930555 INFO (jetty-launcher-1667-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37701/solr
[junit4] 2> 930555 INFO (jetty-launcher-1667-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37701/solr
[junit4] 2> 930555 INFO (zkConnectionManagerCallback-1692-thread-1-processing-n:127.0.0.1:45029_solr) [n:127.0.0.1:45029_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930563 INFO (zkConnectionManagerCallback-1698-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930568 INFO (zkConnectionManagerCallback-1690-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930571 INFO (zkConnectionManagerCallback-1696-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930572 INFO (zkConnectionManagerCallback-1701-thread-1-processing-n:127.0.0.1:46585_solr) [n:127.0.0.1:46585_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930574 INFO (jetty-launcher-1667-thread-2) [n:127.0.0.1:34097_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 930575 INFO (jetty-launcher-1667-thread-2) [n:127.0.0.1:34097_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34097_solr
[junit4] 2> 930575 INFO (zkConnectionManagerCallback-1704-thread-1-processing-n:127.0.0.1:46617_solr) [n:127.0.0.1:46617_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930575 INFO (jetty-launcher-1667-thread-2) [n:127.0.0.1:34097_solr ] o.a.s.c.Overseer Overseer (id=72098999142055944-127.0.0.1:34097_solr-n_0000000000) starting
[junit4] 2> 930582 INFO (jetty-launcher-1667-thread-1) [n:127.0.0.1:46617_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 930583 INFO (jetty-launcher-1667-thread-1) [n:127.0.0.1:46617_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46617_solr
[junit4] 2> 930588 INFO (zkCallback-1703-thread-1-processing-n:127.0.0.1:46617_solr) [n:127.0.0.1:46617_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 930589 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 930593 INFO (jetty-launcher-1667-thread-4) [n:127.0.0.1:46585_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 930594 INFO (jetty-launcher-1667-thread-4) [n:127.0.0.1:46585_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 930598 INFO (jetty-launcher-1667-thread-4) [n:127.0.0.1:46585_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46585_solr
[junit4] 2> 930599 INFO (zkCallback-1703-thread-1-processing-n:127.0.0.1:46617_solr) [n:127.0.0.1:46617_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 930613 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 930626 INFO (jetty-launcher-1667-thread-1) [n:127.0.0.1:46617_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46617.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930628 INFO (jetty-launcher-1667-thread-2) [n:127.0.0.1:34097_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34097_solr
[junit4] 2> 930635 INFO (jetty-launcher-1667-thread-1) [n:127.0.0.1:46617_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46617.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930639 INFO (jetty-launcher-1667-thread-1) [n:127.0.0.1:46617_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46617.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930640 INFO (zkCallback-1703-thread-1-processing-n:127.0.0.1:46617_solr) [n:127.0.0.1:46617_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 930641 INFO (jetty-launcher-1667-thread-1) [n:127.0.0.1:46617_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/.
[junit4] 2> 930641 INFO (OverseerStateUpdate-72098999142055944-127.0.0.1:34097_solr-n_0000000000) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 930645 INFO (jetty-launcher-1667-thread-3) [n:127.0.0.1:45029_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 930646 INFO (jetty-launcher-1667-thread-3) [n:127.0.0.1:45029_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 930647 INFO (jetty-launcher-1667-thread-3) [n:127.0.0.1:45029_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45029_solr
[junit4] 2> 930648 INFO (zkCallback-1703-thread-1-processing-n:127.0.0.1:46617_solr) [n:127.0.0.1:46617_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930665 INFO (zkCallback-1700-thread-1-processing-n:127.0.0.1:46585_solr) [n:127.0.0.1:46585_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (4)
[junit4] 2> 930665 INFO (zkCallback-1691-thread-1-processing-n:127.0.0.1:45029_solr) [n:127.0.0.1:45029_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930665 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930694 INFO (jetty-launcher-1667-thread-2) [n:127.0.0.1:34097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34097.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930696 INFO (jetty-launcher-1667-thread-4) [n:127.0.0.1:46585_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46585.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930702 INFO (jetty-launcher-1667-thread-2) [n:127.0.0.1:34097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34097.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930702 INFO (jetty-launcher-1667-thread-2) [n:127.0.0.1:34097_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34097.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930703 INFO (jetty-launcher-1667-thread-2) [n:127.0.0.1:34097_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node2/.
[junit4] 2> 930710 INFO (jetty-launcher-1667-thread-3) [n:127.0.0.1:45029_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45029.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930711 INFO (jetty-launcher-1667-thread-4) [n:127.0.0.1:46585_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46585.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930712 INFO (jetty-launcher-1667-thread-4) [n:127.0.0.1:46585_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46585.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930713 INFO (jetty-launcher-1667-thread-4) [n:127.0.0.1:46585_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node4/.
[junit4] 2> 930718 INFO (jetty-launcher-1667-thread-3) [n:127.0.0.1:45029_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45029.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930718 INFO (jetty-launcher-1667-thread-3) [n:127.0.0.1:45029_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45029.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930720 INFO (jetty-launcher-1667-thread-3) [n:127.0.0.1:45029_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node3/.
[junit4] 2> 930813 INFO (zkConnectionManagerCallback-1712-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930816 INFO (zkConnectionManagerCallback-1716-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930817 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 930817 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37701/solr ready
[junit4] 2> 930819 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@649d4001{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 930819 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=885706967
[junit4] 2> 930819 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 930819 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7fe32cb9: rootName = solr_46617, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@16efe9be
[junit4] 2> 930823 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 930823 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@56b7c5fb: rootName = solr_46617, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4238b1a5
[junit4] 2> 930826 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 930826 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6964bccc: rootName = solr_46617, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2ad9bdd7
[junit4] 2> 930826 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:46617_solr
[junit4] 2> 930827 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 930827 INFO (zkCallback-1691-thread-1-processing-n:127.0.0.1:45029_solr) [n:127.0.0.1:45029_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930827 INFO (zkCallback-1700-thread-2-processing-n:127.0.0.1:46585_solr) [n:127.0.0.1:46585_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930827 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930827 INFO (zkCallback-1703-thread-1-processing-n:127.0.0.1:46617_solr) [n:127.0.0.1:46617_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930827 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 930827 INFO (zkCallback-1715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930828 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7ffc1e6f{/solr,null,UNAVAILABLE}
[junit4] 2> 930828 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 930829 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:27:37+09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 930829 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 930829 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 930829 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 930830 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7351de94{/solr,null,AVAILABLE}
[junit4] 2> 930830 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@8d1260b{HTTP/1.1,[http/1.1]}{127.0.0.1:41147}
[junit4] 2> 930830 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.Server Started @932759ms
[junit4] 2> 930830 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=35475}
[junit4] 2> 930830 ERROR (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 930830 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 930830 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 930830 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 930830 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-08T07:29:45.056722Z
[junit4] 2> 930831 INFO (zkConnectionManagerCallback-1718-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930831 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 930835 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37701/solr
[junit4] 2> 930836 INFO (zkConnectionManagerCallback-1722-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930837 INFO (zkConnectionManagerCallback-1724-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930840 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 930841 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:35475_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 930841 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:35475_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35475_solr
[junit4] 2> 930842 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930842 INFO (zkCallback-1715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930842 INFO (zkCallback-1691-thread-1-processing-n:127.0.0.1:45029_solr) [n:127.0.0.1:45029_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930842 INFO (zkCallback-1700-thread-2-processing-n:127.0.0.1:46585_solr) [n:127.0.0.1:46585_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930844 INFO (zkCallback-1723-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930886 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:35475_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35475.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930891 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:35475_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35475.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930891 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:35475_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35475.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930892 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:35475_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/.
[junit4] 2> 930933 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.TestCloudConsistency Adding proxy for URL: http://127.0.0.1:41147/solr. Proxy: http://127.0.0.1:35475/solr
[junit4] 2> 930934 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6d98fe42{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 930934 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=788161385
[junit4] 2> 930935 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 930935 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c4e2235: rootName = solr_45029, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@7ebc71ab
[junit4] 2> 930938 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 930938 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5a89bee5: rootName = solr_45029, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4238b1a5
[junit4] 2> 930941 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 930941 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@366001f8: rootName = solr_45029, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2ad9bdd7
[junit4] 2> 930941 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:45029_solr
[junit4] 2> 930942 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930942 INFO (zkCallback-1715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930942 INFO (zkCallback-1723-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930942 INFO (zkCallback-1700-thread-2-processing-n:127.0.0.1:46585_solr) [n:127.0.0.1:46585_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930942 INFO (zkCallback-1691-thread-1-processing-n:127.0.0.1:45029_solr) [n:127.0.0.1:45029_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 930942 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 930942 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 930943 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@39ea1e6e{/solr,null,UNAVAILABLE}
[junit4] 2> 930943 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 930944 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:27:37+09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 930945 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 930945 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 930945 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 930945 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@72d57859{/solr,null,AVAILABLE}
[junit4] 2> 930945 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5ec114c{HTTP/1.1,[http/1.1]}{127.0.0.1:44533}
[junit4] 2> 930945 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.Server Started @932875ms
[junit4] 2> 930945 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42381}
[junit4] 2> 930946 ERROR (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 930946 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 930946 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 930946 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 930946 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-08T07:29:45.172204Z
[junit4] 2> 930947 INFO (zkConnectionManagerCallback-1728-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930947 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 930951 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37701/solr
[junit4] 2> 930952 INFO (zkConnectionManagerCallback-1732-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930953 INFO (zkConnectionManagerCallback-1734-thread-1-processing-n:127.0.0.1:42381_solr) [n:127.0.0.1:42381_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 930955 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:42381_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 930956 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:42381_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 930957 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:42381_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42381_solr
[junit4] 2> 930957 INFO (zkCallback-1715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930957 INFO (zkCallback-1700-thread-2-processing-n:127.0.0.1:46585_solr) [n:127.0.0.1:46585_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930957 INFO (zkCallback-1723-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930958 INFO (zkCallback-1733-thread-1-processing-n:127.0.0.1:42381_solr) [n:127.0.0.1:42381_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930960 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 930986 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:42381_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42381.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930991 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:42381_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42381.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930991 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:42381_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42381.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 930992 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:42381_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node3/.
[junit4] 2> 931019 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.TestCloudConsistency Adding proxy for URL: http://127.0.0.1:44533/solr. Proxy: http://127.0.0.1:42381/solr
[junit4] 2> 931019 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@1b6df8f8{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 931019 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=850319672
[junit4] 2> 931019 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 931019 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@396a1f6f: rootName = solr_46585, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1b2016b3
[junit4] 2> 931023 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 931023 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4f997d17: rootName = solr_46585, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4238b1a5
[junit4] 2> 931025 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 931025 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4a5e4787: rootName = solr_46585, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2ad9bdd7
[junit4] 2> 931026 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:46585_solr
[junit4] 2> 931026 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931027 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 931027 INFO (zkCallback-1715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931027 INFO (zkCallback-1733-thread-1-processing-n:127.0.0.1:42381_solr) [n:127.0.0.1:42381_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931027 INFO (zkCallback-1700-thread-2-processing-n:127.0.0.1:46585_solr) [n:127.0.0.1:46585_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931027 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 931027 INFO (zkCallback-1723-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931029 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7dff2ce4{/solr,null,UNAVAILABLE}
[junit4] 2> 931029 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 931031 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:27:37+09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 931040 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 931040 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 931040 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session Scavenging every 660000ms
[junit4] 2> 931041 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7f102ffd{/solr,null,AVAILABLE}
[junit4] 2> 931041 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@34ec14af{HTTP/1.1,[http/1.1]}{127.0.0.1:36979}
[junit4] 2> 931041 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.Server Started @932971ms
[junit4] 2> 931042 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40661}
[junit4] 2> 931042 ERROR (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 931042 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 931042 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 931042 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 931042 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-08T07:29:45.268449Z
[junit4] 2> 931046 INFO (zkConnectionManagerCallback-1738-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 931046 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 931051 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37701/solr
[junit4] 2> 931059 INFO (zkConnectionManagerCallback-1742-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 931062 INFO (zkConnectionManagerCallback-1744-thread-1-processing-n:127.0.0.1:40661_solr) [n:127.0.0.1:40661_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 931064 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:40661_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 931065 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:40661_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 931066 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:40661_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40661_solr
[junit4] 2> 931066 INFO (zkCallback-1733-thread-1-processing-n:127.0.0.1:42381_solr) [n:127.0.0.1:42381_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931066 INFO (zkCallback-1723-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931066 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931066 INFO (zkCallback-1715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931075 INFO (zkCallback-1743-thread-1-processing-n:127.0.0.1:40661_solr) [n:127.0.0.1:40661_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931105 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:40661_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40661.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 931114 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:40661_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40661.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 931114 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:40661_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40661.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 931115 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:40661_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node4/.
[junit4] 2> 931153 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.TestCloudConsistency Adding proxy for URL: http://127.0.0.1:36979/solr. Proxy: http://127.0.0.1:40661/solr
[junit4] 2> 931153 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@59d79b34{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 931153 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1816295225
[junit4] 2> 931153 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 931153 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@76a2892f: rootName = solr_34097, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@67bcb33d
[junit4] 2> 931164 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 931165 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13650404: rootName = solr_34097, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@4238b1a5
[junit4] 2> 931167 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 931167 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@28f630a: rootName = solr_34097, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2ad9bdd7
[junit4] 2> 931168 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:34097_solr
[junit4] 2> 931168 INFO (zkCallback-1743-thread-1-processing-n:127.0.0.1:40661_solr) [n:127.0.0.1:40661_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931168 INFO (zkCallback-1723-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931168 INFO (zkCallback-1689-thread-1-processing-n:127.0.0.1:34097_solr) [n:127.0.0.1:34097_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931168 INFO (zkCallback-1733-thread-1-processing-n:127.0.0.1:42381_solr) [n:127.0.0.1:42381_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931168 INFO (zkCallback-1715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (3)
[junit4] 2> 931168 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 931169 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 931169 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.Overseer Overseer (id=72098999142055944-127.0.0.1:34097_solr-n_0000000000) closing
[junit4] 2> 931169 INFO (OverseerStateUpdate-72098999142055944-127.0.0.1:34097_solr-n_0000000000) [n:127.0.0.1:34097_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34097_solr
[junit4] 2> 931170 WARN (OverseerAutoScalingTriggerThread-72098999142055944-127.0.0.1:34097_solr-n_0000000000) [n:127.0.0.1:34097_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 931171 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10025a860860008, likely client has closed socket
[junit4] 2> 931171 INFO (zkCallback-1723-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35475_solr
[junit4] 2> 931171 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1f5977a3{/solr,null,UNAVAILABLE}
[junit4] 2> 931171 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 931172 INFO (zkCallback-1723-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.Overseer Overseer (id=72098999142055953-127.0.0.1:35475_solr-n_0000000004) starting
[junit4] 2> 931172 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.Server jetty-9.4.8.v20171121, build timestamp: 2017-11-22T06:27:37+09:00, git hash: 82b8fb23f757335bb3329d540ce37a2a2615f0a8
[junit4] 2> 931183 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 931183 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 931183 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.session Scavenging every 600000ms
[junit4] 2> 931184 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@45744048{/solr,null,AVAILABLE}
[junit4] 2> 931184 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@798119a3{HTTP/1.1,[http/1.1]}{127.0.0.1:45809}
[junit4] 2> 931184 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.e.j.s.Server Started @933114ms
[junit4] 2> 931184 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45975}
[junit4] 2> 931184 ERROR (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 931184 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.3.0
[junit4] 2> 931184 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 931185 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 931185 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2018-03-08T07:29:45.411022Z
[junit4] 2> 931190 INFO (zkConnectionManagerCallback-1748-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 931190 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 931197 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37701/solr
[junit4] 2> 931201 INFO (zkConnectionManagerCallback-1752-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 931203 INFO (zkConnectionManagerCallback-1754-thread-1-processing-n:127.0.0.1:45975_solr) [n:127.0.0.1:45975_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 931206 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:45975_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 931207 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:45975_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 931207 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:45975_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45975_solr
[junit4] 2> 931208 INFO (zkCallback-1715-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931208 INFO (zkCallback-1733-thread-1-processing-n:127.0.0.1:42381_solr) [n:127.0.0.1:42381_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931208 INFO (zkCallback-1743-thread-1-processing-n:127.0.0.1:40661_solr) [n:127.0.0.1:40661_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931208 INFO (zkCallback-1723-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931212 INFO (zkCallback-1753-thread-1-processing-n:127.0.0.1:45975_solr) [n:127.0.0.1:45975_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 931234 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:45975_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45975.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 931239 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:45975_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45975.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 931239 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:45975_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45975.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab92199
[junit4] 2> 931240 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [n:127.0.0.1:45975_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node2/.
[junit4] 2> 931266 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.TestCloudConsistency Adding proxy for URL: http://127.0.0.1:45809/solr. Proxy: http://127.0.0.1:45975/solr
[junit4] 2> 931277 INFO (TEST-TestCloudConsistency.testOutOfSyncReplicasCannotBecomeLeader-seed#[9ABEE8FF6B3AC1A8]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOutOfSyncReplicasCannotBecomeLeader
[junit4] 2> 931277 INFO (SocketProxy-Acceptor-35475) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=48376,localport=35475], receiveBufferSize:531000
[junit4] 2> 931285 INFO (SocketProxy-Acceptor-35475) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=41147,localport=35240], receiveBufferSize=530904
[junit4] 2> 931287 INFO (qtp2031573940-9411) [n:127.0.0.1:35475_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params pullReplicas=0&replicationFactor=3&name=outOfSyncReplicasCannotBecomeLeader-false&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 931296 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection outOfSyncReplicasCannotBecomeLeader-false
[junit4] 2> 931297 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/protwords.txt
[junit4] 2> 931297 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/managed-schema
[junit4] 2> 931298 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/solrconfig.xml
[junit4] 2> 931299 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/synonyms.txt
[junit4] 2> 931299 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/stopwords.txt
[junit4] 2> 931300 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 931301 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 931301 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 931302 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 931302 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 931303 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 931303 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 931304 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 931305 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 931305 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 931306 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 931306 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 931307 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 931307 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 931308 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 931309 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 931309 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 931310 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 931310 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 931311 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 931311 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 931312 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 931312 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 931313 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 931313 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 931314 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 931314 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 931315 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 931316 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 931316 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 931317 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 931317 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 931318 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 931319 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 931319 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 931320 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 931320 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 931321 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 931322 INFO (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/params.json to /configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/params.json
[junit4] 2> 931322 WARN (OverseerThreadFactory-3591-thread-1-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (outOfSyncReplicasCannotBecomeLeader-false) without cores.
[junit4] 2> 931425 INFO (qtp2031573940-9411) [n:127.0.0.1:35475_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 931425 INFO (qtp2031573940-9411) [n:127.0.0.1:35475_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&replicationFactor=3&name=outOfSyncReplicasCannotBecomeLeader-false&nrtReplicas=3&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=&wt=javabin&version=2} status=0 QTime=138
[junit4] 2> 931426 INFO (SocketProxy-Acceptor-45975) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=57016,localport=45975], receiveBufferSize:531000
[junit4] 2> 931426 INFO (SocketProxy-Acceptor-45975) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=45809,localport=33912], receiveBufferSize=530904
[junit4] 2> 931427 INFO (qtp449557847-9490) [n:127.0.0.1:45975_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:35475_solr&action=ADDREPLICA&collection=outOfSyncReplicasCannotBecomeLeader-false&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 931432 INFO (OverseerCollectionConfigSetProcessor-72098999142055953-127.0.0.1:35475_solr-n_0000000004) [n:127.0.0.1:35475_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 931432 INFO (OverseerThreadFactory-3591-thread-2-processing-n:127.0.0.1:35475_solr) [n:127.0.0.1:35475_solr ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:35475_solr for creating new replica
[junit4] 2> 931433 INFO (OverseerStateUpdate-72098999142055953-127.0.0.1:35475_solr-n_0000000004) [n:127.0.0.1:35475_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"outOfSyncReplicasCannotBecomeLeader-false",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:35475/solr",
[junit4] 2> "node_name":"127.0.0.1:35475_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 931635 INFO (SocketProxy-Acceptor-35475) [ ] o.a.s.c.SocketProxy accepted Socket[addr=/127.0.0.1,port=48386,localport=35475], receiveBufferSize:531000
[junit4] 2> 931635 INFO (SocketProxy-Acceptor-35475) [ ] o.a.s.c.SocketProxy proxy connection Socket[addr=/127.0.0.1,port=41147,localport=35250], receiveBufferSize=530904
[junit4] 2> 931636 INFO (qtp2031573940-9417) [n:127.0.0.1:35475_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node62&collection.configName=outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED&name=outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61&action=CREATE&collection=outOfSyncReplicasCannotBecomeLeader-false&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 931636 INFO (qtp2031573940-9417) [n:127.0.0.1:35475_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 931641 INFO (qtp2031573940-9417) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.c.RequestParams conf resource params.json loaded . version : 0
[junit4] 2> 931641 INFO (qtp2031573940-9417) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.c.RequestParams request params refreshed to version 0
[junit4] 2> 931642 WARN (qtp2031573940-9417) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../contrib/extraction/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../contrib/extraction/lib
[junit4] 2> 931642 WARN (qtp2031573940-9417) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../dist filtered by solr-cell-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../dist
[junit4] 2> 931642 WARN (qtp2031573940-9417) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../contrib/clustering/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../contrib/clustering/lib
[junit4] 2> 931642 WARN (qtp2031573940-9417) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../dist filtered by solr-clustering-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../dist
[junit4] 2> 931642 WARN (qtp2031573940-9417) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../contrib/langid/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../contrib/langid/lib
[junit4] 2> 931642 WARN (qtp2031573940-9417) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../dist filtered by solr-langid-\d.*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../dist
[junit4] 2> 931642 WARN (qtp2031573940-9417) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.c.SolrConfig Couldn't add files from /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../contrib/velocity/lib filtered by .*\.jar to classpath: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001/tempDir-001/node1/outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61/../../../../contrib/velocity/lib
[junit4] 2> 931642 W
[...truncated too long message...]
s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7017d9ee: rootName = solr_35475, domain = solr.core.outOfSyncReplicasCannotBecomeLeader-false.shard1.replica_n61, service url = null, agent id = null] for registry solr.core.outOfSyncReplicasCannotBecomeLeader-false.shard1.replica_n61 / com.codahale.metrics.MetricRegistry@3a72b075
[junit4] 2> 990342 INFO (coreCloseExecutor-3709-thread-1) [n:127.0.0.1:42381_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node64 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n63] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.outOfSyncReplicasCannotBecomeLeader-false.shard1.leader, tag=927511196
[junit4] 2> 990343 INFO (coreCloseExecutor-3708-thread-1) [n:127.0.0.1:40661_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node66 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n65] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.outOfSyncReplicasCannotBecomeLeader-false.shard1.leader, tag=1652449871
[junit4] 2> 990345 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x10025a860860023, likely client has closed socket
[junit4] 2> 990345 INFO (coreCloseExecutor-3710-thread-1) [n:127.0.0.1:35475_solr c:outOfSyncReplicasCannotBecomeLeader-false s:shard1 r:core_node62 x:outOfSyncReplicasCannotBecomeLeader-false_shard1_replica_n61] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.outOfSyncReplicasCannotBecomeLeader-false.shard1.leader, tag=558628424
[junit4] 2> 990345 INFO (jetty-closer-1668-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@4eacfcc0{/solr,null,UNAVAILABLE}
[junit4] 2> 990345 INFO (jetty-closer-1668-thread-3) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 990347 INFO (jetty-closer-1668-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@38b6e996{/solr,null,UNAVAILABLE}
[junit4] 2> 990347 INFO (jetty-closer-1668-thread-1) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 990355 INFO (jetty-closer-1668-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@823b75e{/solr,null,UNAVAILABLE}
[junit4] 2> 990356 INFO (jetty-closer-1668-thread-2) [ ] o.e.j.s.session Stopped scavenging
[junit4] 2> 990356 ERROR (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-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> 990357 INFO (SUITE-TestCloudConsistency-seed#[9ABEE8FF6B3AC1A8]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37701 37701
[junit4] 2> 990382 INFO (Thread-2256) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37701 37701
[junit4] 2> 990383 WARN (Thread-2256) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 51 /solr/collections/outOfSyncReplicasCannotBecomeLeader-true/terms/shard1
[junit4] 2> 42 /solr/collections/outOfSyncReplicasCannotBecomeLeader-false/terms/shard1
[junit4] 2> 13 /solr/clusterprops.json
[junit4] 2> 13 /solr/aliases.json
[junit4] 2> 12 /solr/security.json
[junit4] 2> 7 /solr/configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED/managed-schema
[junit4] 2> 7 /solr/collections/outOfSyncReplicasCannotBecomeLeader-false/collectionprops.json
[junit4] 2> 7 /solr/configs/outOfSyncReplicasCannotBecomeLeader-false.AUTOCREATED
[junit4] 2> 6 /solr/collections/outOfSyncReplicasCannotBecomeLeader-true/collectionprops.json
[junit4] 2> 6 /solr/configs/outOfSyncReplicasCannotBecomeLeader-true.AUTOCREATED
[junit4] 2> 6 /solr/configs/outOfSyncReplicasCannotBecomeLeader-true.AUTOCREATED/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 93 /solr/collections/outOfSyncReplicasCannotBecomeLeader-false/state.json
[junit4] 2> 81 /solr/collections/outOfSyncReplicasCannotBecomeLeader-true/state.json
[junit4] 2> 13 /solr/clusterstate.json
[junit4] 2> 5 /solr/autoscaling.json
[junit4] 2> 3 /solr/overseer_elect/election/72098999142055944-127.0.0.1:34097_solr-n_0000000000
[junit4] 2> 2 /solr/overseer_elect/election/72098999142055947-127.0.0.1:46585_solr-n_0000000002
[junit4] 2> 2 /solr/overseer_elect/election/72098999142055962-127.0.0.1:45975_solr-n_0000000007
[junit4] 2> 2 /solr/collections/outOfSyncReplicasCannotBecomeLeader-false/leader_elect/shard1/election/72098999142055974-core_node62-n_0000000016
[junit4] 2> 2 /solr/collections/outOfSyncReplicasCannotBecomeLeader-true/leader_elect/shard1/election/72098999142055965-core_node62-n_0000000000
[junit4] 2> 2 /solr/collections/outOfSyncReplicasCannotBecomeLeader-false/leader_elect/shard1/election/72098999142055965-core_node62-n_0000000009
[junit4] 2> 2 /solr/overseer_elect/election/72098999142055968-127.0.0.1:42381_solr-n_0000000009
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/live_nodes
[junit4] 2> 13 /solr/collections
[junit4] 2> 5 /solr/overseer/queue
[junit4] 2> 5 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2> 5 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.x-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.TestCloudConsistency_9ABEE8FF6B3AC1A8-001
[junit4] 2> Mar 08, 2018 7:30:44 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 6 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=54, maxMBSortInHeap=6.746994471584564, sim=RandomSimilarity(queryNorm=false): {}, locale=sr-Latn, timezone=SystemV/EST5EDT
[junit4] 2> NOTE: Linux 4.13.0-36-generic amd64/Oracle Corporation 10 (64-bit)/cpus=8,threads=1,free=210992800,total=524812288
[junit4] 2> NOTE: All tests run in this JVM: [CollectionStateFormat2Test, TestQueryTypes, SpellPossibilityIteratorTest, WordBreakSolrSpellCheckerTest, TestSha256AuthenticationProvider, SecurityConfHandlerTest, TestBulkSchemaAPI, TestXmlQParser, TestUseDocValuesAsStored2, ConfigSetsAPITest, CoreAdminHandlerTest, UnloadDistributedZkTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, TestSchemaResource, BlockJoinFacetDistribTest, URLClassifyProcessorTest, ShowFileRequestHandlerTest, TestSchemalessBufferedUpdates, TimeRoutedAliasUpdateProcessorTest, MoveReplicaHDFSFailoverTest, AtomicUpdatesTest, TestUpdate, TestDistribStateManager, SolrMetricReporterTest, TestReRankQParserPlugin, BaseCdcrDistributedZkTest, TestDocBasedVersionConstraints, BasicDistributedZk2Test, TestDynamicLoading, TestTestInjection, TestTlogReplica, PingRequestHandlerTest, TestRandomFlRTGCloud, TestSerializedLuceneMatchVersion, DisMaxRequestHandlerTest, TestSubQueryTransformer, TestInitParams, DateFieldTest, HLLUtilTest, RecoveryZkTest, DistribJoinFromCollectionTest, ConnectionReuseTest, TestInitQParser, DistributedExpandComponentTest, TestAnalyzedSuggestions, SimpleCollectionCreateDeleteTest, UniqFieldsUpdateProcessorFactoryTest, TestCryptoKeys, TestMacros, TestBinaryResponseWriter, RulesTest, TestQuerySenderListener, ClusterStateTest, NodeAddedTriggerTest, V2ApiIntegrationTest, RegexBoostProcessorTest, TestDistributedMissingSort, TestFaceting, CachingDirectoryFactoryTest, TestCoreDiscovery, SpellCheckComponentTest, SearchHandlerTest, TestBadConfig, MultiTermTest, RestartWhileUpdatingTest, TestDFISimilarityFactory, TestSolr4Spatial2, HttpPartitionTest, DocValuesMultiTest, MultiThreadedOCPTest, DirectSolrSpellCheckerTest, TestAtomicUpdateErrorCases, DocValuesNotIndexedTest, TestCloudRecovery, QueryResultKeyTest, DeleteNodeTest, HdfsAutoAddReplicasIntegrationTest, FileBasedSpellCheckerTest, TestCodecSupport, TestFieldTypeCollectionResource, TestManagedSchemaThreadSafety, CloudMLTQParserTest, TestInPlaceUpdatesDistrib, TestFieldCacheReopen, TestPrepRecovery, SaslZkACLProviderTest, TestAddFieldRealTimeGet, WrapperMergePolicyFactoryTest, TestJmxIntegration, HdfsBasicDistributedZkTest, TestCloudConsistency]
[junit4] Completed [352/780 (1!)] on J2 in 60.47s, 2 tests, 1 failure <<< FAILURES!
[...truncated 44174 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-7.x-Linux/1486/consoleText
[repro] Revision: ae97500ec2198280e4dc96bd8b5d64b65034cd5c
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=TestCloudConsistency -Dtests.method=testOutOfSyncReplicasCannotBecomeLeader -Dtests.seed=9ABEE8FF6B3AC1A8 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-Latn -Dtests.timezone=SystemV/EST5EDT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TestCloudConsistency
[repro] ant compile-test
[...truncated 3315 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestCloudConsistency" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=9ABEE8FF6B3AC1A8 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sr-Latn -Dtests.timezone=SystemV/EST5EDT -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 81 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.cloud.TestCloudConsistency
[repro] Exiting with code 0
[...truncated 40 lines...]