Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7733/ Java: 64bit/jdk-9.0.4 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed. FAILED: org.apache.solr.cloud.OverseerTest.testLatchWatcher Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([3FA4603116430354:88346F0CF601129C]:0) at org.junit.Assert.fail(Assert.java:86) at org.junit.Assert.assertTrue(Assert.java:41) at org.junit.Assert.assertTrue(Assert.java:52) at org.apache.solr.cloud.OverseerTest.testLatchWatcher(OverseerTest.java:1588) 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:1750) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.base/java.lang.Thread.run(Thread.java:844) Build Log: [...truncated 12669 lines...] [junit4] Suite: org.apache.solr.cloud.OverseerTest [junit4] 2> 103023 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom [junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerTest_3FA4603116430354-001\init-core-data-001 [junit4] 2> 103023 WARN (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=12 numCloses=12 [junit4] 2> 103023 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false [junit4] 2> 103440 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0) [junit4] 2> 103518 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 103519 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 103519 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 103618 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:50415 [junit4] 2> 103618 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50415 [junit4] 2> 103618 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50415 [junit4] 2> 103657 INFO (zkConnectionManagerCallback-259-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 103665 INFO (zkConnectionManagerCallback-261-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 103665 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 103665 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 103670 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDoubleAssignment [junit4] 2> 104744 INFO (zkConnectionManagerCallback-265-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 104839 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 104847 INFO (zkCallback-264-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 104851 INFO (zkConnectionManagerCallback-269-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 104874 WARN (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@14a4d51e[provider=null,keyStore=null,trustStore=null] [junit4] 2> 107290 INFO (zkConnectionManagerCallback-276-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 107712 INFO (zkConnectionManagerCallback-281-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 107714 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 107717 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 107748 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 107766 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451587-127.0.0.1:50415_solr-n_0000000000) starting [junit4] 2> 108024 INFO (OverseerStateUpdate-72057736432451587-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 2> 108030 INFO (OverseerStateUpdate-72057736432451587-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108058 INFO (zkConnectionManagerCallback-286-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 108062 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108065 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 108190 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 108193 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72057736432451586-node1_core1-n_0000000000 [junit4] 2> 108204 INFO (zkCallback-264-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 108204 INFO (zkCallback-280-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 108204 INFO (zkCallback-268-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 108204 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 108219 INFO (zkCallback-285-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 108247 INFO (zkConnectionManagerCallback-288-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 108268 INFO (zkCallback-280-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108268 INFO (zkCallback-268-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108268 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108268 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108274 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 108277 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72057736432451591-node1_core1-n_0000000001 [junit4] 2> 108291 INFO (zkCallback-287-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108294 INFO (zkCallback-280-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 108294 INFO (zkCallback-287-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 108294 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 108295 INFO (zkCallback-285-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@76f66ea4[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 108311 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 0x100002127600005, likely client has closed socket [junit4] 2> 108312 INFO (closeThreadPool-292-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72057736432451587-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 108312 INFO (OverseerStateUpdate-72057736432451587-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 108313 INFO (OverseerAutoScalingTriggerThread-72057736432451587-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 108316 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 108440 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDoubleAssignment [junit4] 2> 108456 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testShardAssignment [junit4] 2> 108523 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=587872755 [junit4] 2> 108719 INFO (zkConnectionManagerCallback-294-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 108730 WARN (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1b9b6153[provider=null,keyStore=null,trustStore=null] [junit4] 2> 108753 INFO (zkConnectionManagerCallback-301-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 108773 INFO (zkConnectionManagerCallback-306-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 108774 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 108781 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 108782 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451592-127.0.0.1:50415_solr-n_0000000000) starting [junit4] 2> 108805 INFO (OverseerStateUpdate-72057736432451592-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 2> 108831 INFO (zkConnectionManagerCallback-310-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 108850 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108854 INFO (zkCallback-305-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108854 INFO (zkCallback-293-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108854 INFO (zkCallback-309-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 108973 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 108976 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72057736432451595-127.0.0.1_core1-n_0000000000 [junit4] 2> 109102 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2 [junit4] 2> 109104 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72057736432451595-127.0.0.1_core2-n_0000000000 [junit4] 2> 109228 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3 [junit4] 2> 109237 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard3/leader after winning as /collections/collection1/leader_elect/shard3/election/72057736432451595-127.0.0.1_core3-n_0000000000 [junit4] 2> 109359 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 109477 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2 [junit4] 2> 109614 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3 [junit4] 2> 109635 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration. [junit4] 2> 109635 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration. [junit4] 2> 109635 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase No version found for ephemeral leader parent node, won't remove previous leader registration. [junit4] 2> 109635 WARN (zkCallback-309-thread-1) [ ] o.a.s.c.LeaderElector Our node is no longer in line to be leader [junit4] 2> 109640 WARN (zkCallback-309-thread-1) [ ] o.a.s.c.LeaderElector Our node is no longer in line to be leader [junit4] 2> 109640 WARN (zkCallback-309-thread-1) [ ] o.a.s.c.LeaderElector Our node is no longer in line to be leader [junit4] 2> 109643 INFO (zkCallback-305-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 109643 INFO (zkCallback-293-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 109643 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 109643 INFO (zkCallback-309-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@21720337[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 109658 INFO (closeThreadPool-314-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72057736432451592-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 109658 INFO (OverseerStateUpdate-72057736432451592-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 109660 INFO (OverseerAutoScalingTriggerThread-72057736432451592-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 109662 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 109848 INFO (TEST-OverseerTest.testShardAssignment-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testShardAssignment [junit4] 2> 109855 INFO (TEST-OverseerTest.testLatchWatcher-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLatchWatcher [junit4] 2> 110474 INFO (TEST-OverseerTest.testLatchWatcher-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLatchWatcher [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=OverseerTest -Dtests.method=testLatchWatcher -Dtests.seed=3FA4603116430354 -Dtests.slow=true -Dtests.locale=sw -Dtests.timezone=America/Bogota -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] FAILURE 0.63s J1 | OverseerTest.testLatchWatcher <<< [junit4] > Throwable #1: java.lang.AssertionError [junit4] > at __randomizedtesting.SeedInfo.seed([3FA4603116430354:88346F0CF601129C]:0) [junit4] > at org.apache.solr.cloud.OverseerTest.testLatchWatcher(OverseerTest.java:1588) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564) [junit4] > at java.base/java.lang.Thread.run(Thread.java:844) [junit4] 2> 110493 INFO (TEST-OverseerTest.testRemovalOfLastReplica-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testRemovalOfLastReplica [junit4] 2> 110878 INFO (zkConnectionManagerCallback-320-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 110887 WARN (TEST-OverseerTest.testRemovalOfLastReplica-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@35a62fba[provider=null,keyStore=null,trustStore=null] [junit4] 2> 110898 INFO (zkConnectionManagerCallback-327-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 110907 INFO (zkConnectionManagerCallback-332-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 110921 INFO (TEST-OverseerTest.testRemovalOfLastReplica-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 110928 INFO (TEST-OverseerTest.testRemovalOfLastReplica-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 110932 INFO (TEST-OverseerTest.testRemovalOfLastReplica-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451596-127.0.0.1:50415_solr-n_0000000000) starting [junit4] 2> 110977 INFO (OverseerStateUpdate-72057736432451596-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@42ab08df[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 111557 INFO (closeThreadPool-334-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72057736432451596-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 111557 INFO (OverseerStateUpdate-72057736432451596-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 111557 INFO (OverseerAutoScalingTriggerThread-72057736432451596-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 111563 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 111617 INFO (TEST-OverseerTest.testRemovalOfLastReplica-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testRemovalOfLastReplica [junit4] 2> 111622 INFO (TEST-OverseerTest.testStateChange-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateChange [junit4] 2> 111781 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1533492730 [junit4] 2> 111781 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1539321200 [junit4] 2> 112001 INFO (zkConnectionManagerCallback-338-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 112008 WARN (TEST-OverseerTest.testStateChange-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@1a920e34[provider=null,keyStore=null,trustStore=null] [junit4] 2> 112022 INFO (zkConnectionManagerCallback-345-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 112046 INFO (zkConnectionManagerCallback-350-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 112051 INFO (TEST-OverseerTest.testStateChange-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 112070 INFO (TEST-OverseerTest.testStateChange-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 112071 INFO (TEST-OverseerTest.testStateChange-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451599-127.0.0.1:50415_solr-n_0000000000) starting [junit4] 2> 112084 INFO (OverseerStateUpdate-72057736432451599-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@5042bd8a[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 112325 INFO (closeThreadPool-352-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72057736432451599-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 112325 INFO (OverseerStateUpdate-72057736432451599-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 112327 INFO (OverseerAutoScalingTriggerThread-72057736432451599-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 112330 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 112428 INFO (TEST-OverseerTest.testStateChange-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateChange [junit4] 2> 112434 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOverseerFailure [junit4] 2> 112582 INFO (zkConnectionManagerCallback-356-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 112590 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 112593 INFO (zkCallback-355-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 112597 INFO (zkConnectionManagerCallback-360-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 112614 WARN (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@2cad09f5[provider=null,keyStore=null,trustStore=null] [junit4] 2> 112830 INFO (zkConnectionManagerCallback-367-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 112845 INFO (zkConnectionManagerCallback-372-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 112847 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 112850 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 112855 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 112855 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451603-127.0.0.1:50415_solr-n_0000000000) starting [junit4] 2> 113072 INFO (OverseerStateUpdate-72057736432451603-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 2> 113075 INFO (OverseerStateUpdate-72057736432451603-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 113104 INFO (zkConnectionManagerCallback-377-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 113107 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 113112 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 113224 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 113238 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72057736432451602-node1_core1-n_0000000000 [junit4] 2> 113277 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 113278 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72057736432451602-node1_core1-n_0000000001 [junit4] 2> 113470 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 113473 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72057736432451602-node1_core1-n_0000000002 [junit4] 2> 113474 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 0x100002127600013, likely client has closed socket [junit4] 2> 113480 INFO (zkConnectionManagerCallback-379-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 113480 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451603-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 113480 INFO (OverseerAutoScalingTriggerThread-72057736432451603-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 113480 INFO (OverseerStateUpdate-72057736432451603-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 113485 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 113491 WARN (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@25d8209c[provider=null,keyStore=null,trustStore=null] [junit4] 2> 113497 INFO (zkConnectionManagerCallback-386-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 113506 INFO (zkConnectionManagerCallback-391-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 113509 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 113512 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 113514 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 113516 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451607-127.0.0.1:50415_solr-n_0000000001) starting [junit4] 2> 113547 INFO (OverseerStateUpdate-72057736432451607-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 2> 113551 INFO (OverseerStateUpdate-72057736432451607-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 113792 INFO (zkCallback-371-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 113792 INFO (zkCallback-355-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 113793 INFO (zkCallback-376-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 113793 INFO (zkCallback-390-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 113793 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 113792 INFO (zkCallback-378-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@38f22725[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@6127bc7c[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 113829 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=174622857 [junit4] 2> 113829 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=2027945366 [junit4] 2> 113829 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=448104346 [junit4] 2> 113834 INFO (closeThreadPool-393-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72057736432451603-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 113834 INFO (closeThreadPool-393-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72057736432451607-127.0.0.1:50415_solr-n_0000000001) closing [junit4] 2> 113838 INFO (OverseerAutoScalingTriggerThread-72057736432451607-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 113838 INFO (OverseerStateUpdate-72057736432451607-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 113841 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 113895 INFO (TEST-OverseerTest.testOverseerFailure-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOverseerFailure [junit4] 2> 113900 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBadQueueItem [junit4] 2> 114008 INFO (zkConnectionManagerCallback-395-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 114029 WARN (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@58536093[provider=null,keyStore=null,trustStore=null] [junit4] 2> 114050 INFO (zkConnectionManagerCallback-402-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 114055 INFO (zkConnectionManagerCallback-407-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 114060 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 114063 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 114065 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451610-127.0.0.1:50415_solr-n_0000000000) starting [junit4] 2> 114080 INFO (OverseerStateUpdate-72057736432451610-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 2> 114094 INFO (zkConnectionManagerCallback-411-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 114105 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 114108 INFO (zkCallback-406-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 114108 INFO (zkCallback-394-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 114108 INFO (zkCallback-410-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 114218 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 114223 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72057736432451613-127.0.0.1_core1-n_0000000000 [junit4] 2> 114334 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2 [junit4] 2> 114337 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72057736432451613-127.0.0.1_core2-n_0000000000 [junit4] 2> 114458 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard3 [junit4] 2> 114460 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard3/leader after winning as /collections/collection1/leader_elect/shard3/election/72057736432451613-127.0.0.1_core3-n_0000000000 [junit4] 2> 114577 ERROR (OverseerStateUpdate-72057736432451610-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.o.CollectionMutator Skipping invalid Overseer message because it has no collection specified: { [junit4] 2> "operation":"state", [junit4] 2> "state":"active", [junit4] 2> "node_name":"127.0.0.1", [junit4] 2> "core":"core0", [junit4] 2> "core_node_name":"node0", [junit4] 2> "collection":"", [junit4] 2> "shard":"shard1", [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1/solr/"} [junit4] 2> 114577 ERROR (OverseerStateUpdate-72057736432451610-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.o.CollectionMutator Skipping invalid Overseer message because it has no collection specified: { [junit4] 2> "operation":"deletecore", [junit4] 2> "node_name":"127.0.0.1", [junit4] 2> "core":"core0", [junit4] 2> "core_node_name":"node0", [junit4] 2> "collection":""} [junit4] 2> 114685 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2/leaders/shard1 [junit4] 2> 114688 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2/leaders/shard1/leader after winning as /collections/collection2/leader_elect/shard1/election/72057736432451613-127.0.0.1_core1-n_0000000000 [junit4] 2> 114811 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2/leaders/shard2 [junit4] 2> 114813 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2/leaders/shard2/leader after winning as /collections/collection2/leader_elect/shard2/election/72057736432451613-127.0.0.1_core2-n_0000000000 [junit4] 2> 114925 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2/leaders/shard3 [junit4] 2> 114928 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2/leaders/shard3/leader after winning as /collections/collection2/leader_elect/shard3/election/72057736432451613-127.0.0.1_core3-n_0000000000 [junit4] 2> 115044 INFO (zkCallback-406-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 115044 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 115044 INFO (zkCallback-410-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 115044 INFO (zkCallback-394-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@12053f2e[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 115057 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 0x10000212760001c, likely client has closed socket [junit4] 2> 115058 INFO (closeThreadPool-415-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72057736432451610-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 115058 INFO (OverseerStateUpdate-72057736432451610-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 115059 INFO (OverseerAutoScalingTriggerThread-72057736432451610-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 115061 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 115118 INFO (TEST-OverseerTest.testBadQueueItem-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testBadQueueItem [junit4] 2> 115132 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOverseerStatsReset [junit4] 2> 115253 INFO (zkConnectionManagerCallback-419-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 115264 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 115267 INFO (zkCallback-418-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 115269 INFO (zkConnectionManagerCallback-426-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 115271 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 115273 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 115279 WARN (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@314656ef[provider=null,keyStore=null,trustStore=null] [junit4] 2> 115283 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 115284 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451585-127.0.0.1:50415_solr-n_0000000000) starting [junit4] 2> 115309 INFO (OverseerStateUpdate-72057736432451585-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 2> 115425 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 115428 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72057736432451614-node1_core1-n_0000000000 [junit4] 2> 115432 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451585-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 115432 INFO (OverseerAutoScalingTriggerThread-72057736432451585-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 115432 INFO (OverseerStateUpdate-72057736432451585-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 115438 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I (id=72057736432451585-127.0.0.1:50415_solr-n_0000000000) am exiting, but I'm still the leader [junit4] 2> 115438 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451585-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 115440 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451585-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 115442 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451585-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 115442 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 115444 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451585-127.0.0.1:50415_solr-n_0000000001) starting [junit4] 2> 115454 INFO (zkCallback-425-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 115454 INFO (zkCallback-418-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 115454 INFO (zkCallback-260-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 115461 INFO (OverseerStateUpdate-72057736432451585-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@7686ceb3[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 115484 INFO (closeThreadPool-431-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72057736432451585-127.0.0.1:50415_solr-n_0000000001) closing [junit4] 2> 115484 INFO (OverseerStateUpdate-72057736432451585-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 115485 INFO (OverseerAutoScalingTriggerThread-72057736432451585-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 115489 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I (id=72057736432451585-127.0.0.1:50415_solr-n_0000000001) am exiting, but I'm still the leader [junit4] 2> 115489 WARN (ScheduledTrigger-208-thread-2) [ ] o.a.s.c.a.TriggerBase Exception updating trigger state '/autoscaling/triggerState/.scheduled_maintenance' [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /autoscaling/triggerState/.scheduled_maintenance [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1111) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$exists$3(SolrZkClient.java:334) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:334) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.impl.ZkDistribStateManager.hasData(ZkDistribStateManager.java:59) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.TriggerBase.saveState(TriggerBase.java:246) [java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerWrapper.run(ScheduledTriggers.java:642) [java/:?] [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [?:?] [junit4] 2> at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?] [junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:300) [?:?] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [?:?] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [?:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 115490 WARN (ScheduledTrigger-208-thread-1) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger ran but was already closed [junit4] 2> 115490 WARN (ScheduledTrigger-208-thread-1) [ ] o.a.s.c.a.TriggerBase Exception updating trigger state '/autoscaling/triggerState/.auto_add_replicas' [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /autoscaling/triggerState/.auto_add_replicas [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1111) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$exists$3(SolrZkClient.java:334) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:334) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.impl.ZkDistribStateManager.hasData(ZkDistribStateManager.java:59) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.TriggerBase.saveState(TriggerBase.java:246) [java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerWrapper.run(ScheduledTriggers.java:642) [java/:?] [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [?:?] [junit4] 2> at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?] [junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:300) [?:?] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [?:?] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [?:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 115538 INFO (TEST-OverseerTest.testOverseerStatsReset-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOverseerStatsReset [junit4] 2> 115548 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1086620177 [junit4] 2> 115548 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=237401664 [junit4] 2> 115549 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=152176991 [junit4] 2> 115551 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDownNodeFailover [junit4] 2> 115672 INFO (zkConnectionManagerCallback-433-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 115682 WARN (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@8645ddb[provider=null,keyStore=null,trustStore=null] [junit4] 2> 115684 INFO (zkConnectionManagerCallback-440-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 115693 INFO (zkConnectionManagerCallback-445-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 115700 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 115703 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 115705 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451616-127.0.0.1:50415_solr-n_0000000000) starting [junit4] 2> 115720 INFO (OverseerStateUpdate-72057736432451616-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 2> 115727 INFO (zkConnectionManagerCallback-449-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 115734 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 115736 INFO (zkCallback-448-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 115736 INFO (zkCallback-432-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 115737 INFO (zkCallback-444-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 115740 INFO (zkConnectionManagerCallback-456-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 115742 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 115743 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 115856 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection0/leaders/shard1 [junit4] 2> 115858 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection0/leaders/shard1/leader after winning as /collections/collection0/leader_elect/shard1/election/72057736432451619-127.0.0.1_core1-n_0000000000 [junit4] 2> 115974 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1 [junit4] 2> 115974 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72057736432451619-127.0.0.1_core1-n_0000000000 [junit4] 2> 116180 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection2/leaders/shard1 [junit4] 2> 116184 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection2/leaders/shard1/leader after winning as /collections/collection2/leader_elect/shard1/election/72057736432451619-127.0.0.1_core1-n_0000000000 [junit4] 2> 116303 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection3/leaders/shard1 [junit4] 2> 116306 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection3/leaders/shard1/leader after winning as /collections/collection3/leader_elect/shard1/election/72057736432451619-127.0.0.1_core1-n_0000000000 [junit4] 2> 116430 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection4/leaders/shard1 [junit4] 2> 116440 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection4/leaders/shard1/leader after winning as /collections/collection4/leader_elect/shard1/election/72057736432451619-127.0.0.1_core1-n_0000000000 [junit4] 2> 116558 INFO (zkConnectionManagerCallback-458-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 116558 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451616-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 116559 INFO (OverseerAutoScalingTriggerThread-72057736432451616-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 116559 INFO (OverseerStateUpdate-72057736432451616-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 116562 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 116568 WARN (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@67b0d1ed[provider=null,keyStore=null,trustStore=null] [junit4] 2> 116572 INFO (zkConnectionManagerCallback-465-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 116578 INFO (zkConnectionManagerCallback-470-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 116581 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 116583 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 116585 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 116586 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451621-127.0.0.1:50415_solr-n_0000000001) starting [junit4] 2> 116597 INFO (zkCallback-448-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 116597 INFO (zkCallback-444-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 116598 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 116598 INFO (zkCallback-455-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 116601 INFO (zkCallback-469-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0) [junit4] 2> 116603 INFO (OverseerStateUpdate-72057736432451621-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@2d2e0aca[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 116616 WARN (ScheduledTrigger-218-thread-1) [ ] o.a.s.c.a.TriggerEventQueue Exception peeking queue of trigger .scheduled_maintenance [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /autoscaling/events/.scheduled_maintenance [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1541) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getChildren$4(SolrZkClient.java:346) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:346) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.ZkDistributedQueue.fetchZkChildren(ZkDistributedQueue.java:415) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.ZkDistributedQueue.firstChild(ZkDistributedQueue.java:392) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.ZkDistributedQueue.firstElement(ZkDistributedQueue.java:509) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.ZkDistributedQueue.peek(ZkDistributedQueue.java:150) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.TriggerEventQueue.peekEvent(TriggerEventQueue.java:69) [java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers$TriggerWrapper.run(ScheduledTriggers.java:610) [java/:?] [junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:514) [?:?] [junit4] 2> at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305) [?:?] [junit4] 2> at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:300) [?:?] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) [?:?] [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) [?:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@5d73f350[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 116625 INFO (closeThreadPool-472-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72057736432451616-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 116625 INFO (closeThreadPool-472-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72057736432451621-127.0.0.1:50415_solr-n_0000000001) closing [junit4] 2> 116625 INFO (OverseerCollectionConfigSetProcessor-72057736432451621-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.OverseerTaskProcessor According to ZK I (id=72057736432451621-127.0.0.1:50415_solr-n_0000000001) am no longer a leader. propsId=null [junit4] 2> 116625 INFO (OverseerStateUpdate-72057736432451621-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 116630 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 118116 WARN (OverseerAutoScalingTriggerThread-72057736432451621-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Error removing old markers [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /autoscaling/nodeLost [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1541) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getChildren$4(SolrZkClient.java:346) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:346) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.impl.ZkDistribStateManager.listData(ZkDistribStateManager.java:69) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.impl.ZkDistribStateManager.listData(ZkDistribStateManager.java:80) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.cloud.DistribStateManager.listTree(DistribStateManager.java:104) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.cloud.DistribStateManager.removeRecursively(DistribStateManager.java:123) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.removeMarkers(OverseerTriggerThread.java:269) [java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:261) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 118118 WARN (OverseerAutoScalingTriggerThread-72057736432451621-127.0.0.1:50415_solr-n_0000000001) [ ] o.a.s.c.a.OverseerTriggerThread Error removing old markers [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /autoscaling/nodeAdded [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getChildren(ZooKeeper.java:1541) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getChildren$4(SolrZkClient.java:346) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:346) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.impl.ZkDistribStateManager.listData(ZkDistribStateManager.java:69) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.impl.ZkDistribStateManager.listData(ZkDistribStateManager.java:80) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.cloud.DistribStateManager.listTree(DistribStateManager.java:104) ~[java/:?] [junit4] 2> at org.apache.solr.client.solrj.cloud.DistribStateManager.removeRecursively(DistribStateManager.java:123) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.removeMarkers(OverseerTriggerThread.java:269) [java/:?] [junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:262) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 118278 INFO (TEST-OverseerTest.testDownNodeFailover-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDownNodeFailover [junit4] 2> 118284 INFO (TEST-OverseerTest.testShardLeaderChange-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testShardLeaderChange [junit4] 2> 118530 WARN (TEST-OverseerTest.testShardLeaderChange-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@5cd8122a[provider=null,keyStore=null,trustStore=null] [junit4] 2> 118534 INFO (zkConnectionManagerCallback-478-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118537 INFO (zkConnectionManagerCallback-481-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118551 WARN (Thread-111) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@224397a0[provider=null,keyStore=null,trustStore=null] [junit4] 2> 118554 WARN (TEST-OverseerTest.testShardLeaderChange-seed#[3FA4603116430354]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@7321e9c5[provider=null,keyStore=null,trustStore=null] [junit4] 2> 118554 INFO (zkConnectionManagerCallback-492-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118554 INFO (zkConnectionManagerCallback-495-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118566 INFO (zkConnectionManagerCallback-500-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118566 INFO (zkConnectionManagerCallback-505-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118569 INFO (TEST-OverseerTest.testShardLeaderChange-seed#[3FA4603116430354]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 118571 INFO (Thread-111) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 118573 INFO (TEST-OverseerTest.testShardLeaderChange-seed#[3FA4603116430354]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 118574 INFO (TEST-OverseerTest.testShardLeaderChange-seed#[3FA4603116430354]) [ ] o.a.s.c.Overseer Overseer (id=72057736432451625-127.0.0.1:50415_solr-n_0000000000) starting [junit4] 2> 118588 INFO (OverseerStateUpdate-72057736432451625-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 2> 118598 INFO (zkConnectionManagerCallback-507-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118614 INFO (zkCallback-260-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 118618 INFO (zkCallback-506-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 118618 INFO (zkCallback-499-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 118618 INFO (zkCallback-504-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 118618 INFO (zkCallback-480-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 118679 INFO (Thread-111) [ ] o.a.s.c.OverseerTest Killing overseer. [junit4] 2> 118684 INFO (zkConnectionManagerCallback-511-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118684 INFO (Thread-111) [ ] o.a.s.c.Overseer Overseer (id=72057736432451625-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 118688 INFO (OverseerStateUpdate-72057736432451625-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 118684 INFO (OverseerAutoScalingTriggerThread-72057736432451625-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 118699 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer I (id=72057736432451625-127.0.0.1:50415_solr-n_0000000000) am exiting, but I'm still the leader [junit4] 2> 118700 WARN (Thread-111) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for SslContextFactory@3521ef4[provider=null,keyStore=null,trustStore=null] [junit4] 2> 118715 INFO (zkConnectionManagerCallback-518-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118742 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1059968688 [junit4] 2> 118743 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1528635214 [junit4] 2> 118743 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1723135515 [junit4] 2> 118743 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=2138395491 [junit4] 2> 118743 INFO (zkConnectionManagerCallback-523-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected [junit4] 2> 118751 INFO (Thread-111) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 118758 INFO (Thread-111) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50415/solr ready [junit4] 2> 118761 INFO (Thread-111) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50415_solr [junit4] 2> 118763 INFO (Thread-111) [ ] o.a.s.c.Overseer Overseer (id=72057736432451631-127.0.0.1:50415_solr-n_0000000002) starting [junit4] 2> 118770 INFO (OverseerStateUpdate-72057736432451631-127.0.0.1:50415_solr-n_0000000002) [ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50415_solr [junit4] 2> 118778 INFO (OverseerStateUpdate-72057736432451631-127.0.0.1:50415_solr-n_0000000002) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 118826 INFO (TEST-OverseerTest.testShardLeaderChange-seed#[3FA4603116430354]) [ ] o.a.s.c.Sha [...truncated too long message...] teWriter.java:204) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.enqueueUpdate(ZkStateWriter.java:130) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.processQueueItem(Overseer.java:303) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:177) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 124552 ERROR (OverseerStateUpdate-72057736432451678-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Exception in Overseer main queue loop [junit4] 2> org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /collections/collection1/state.json [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:114) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:792) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:415) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:415) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.writePendingUpdates(ZkStateWriter.java:204) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:247) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 124558 WARN (OverseerStateUpdate-72057736432451678-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Exception when process message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "replicationFactor":"1", [junit4] 2> "numShards":"1", [junit4] 2> "stateFormat":"2", [junit4] 2> "createNodeSet":""}, consider as bad message and poll out from the queue [junit4] 2> 124571 ERROR (OverseerStateUpdate-72057736432451678-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Exception in Overseer when process message from work queue, retrying [junit4] 2> org.apache.zookeeper.KeeperException$NoNodeException: KeeperErrorCode = NoNode for /collections/collection1/state.json [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:114) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:792) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$create$7(SolrZkClient.java:415) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:415) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.writePendingUpdates(ZkStateWriter.java:204) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.overseer.ZkStateWriter.enqueueUpdate(ZkStateWriter.java:130) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.processQueueItem(Overseer.java:303) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.run(Overseer.java:177) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 1> Done close org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@6fb48f1c[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 1] [junit4] 2> 124604 INFO (closeThreadPool-735-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72057736432451678-127.0.0.1:50415_solr-n_0000000000) closing [junit4] 2> 124604 INFO (OverseerStateUpdate-72057736432451678-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50415_solr [junit4] 2> 124604 INFO (OverseerAutoScalingTriggerThread-72057736432451678-127.0.0.1:50415_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting. [junit4] 2> 124612 WARN (OverseerExitThread) [ ] o.a.s.c.Overseer Error communicating with ZooKeeper [junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:130) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:54) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1221) ~[zookeeper-3.4.13.jar:3.4.13-2d71af4dbe22557fda74f9a9b4309b15a7487f03] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:71) ~[java/:?] [junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:358) ~[java/:?] [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:319) [java/:?] [junit4] 2> at java.lang.Thread.run(Thread.java:844) [?:?] [junit4] 2> 124756 INFO (TEST-OverseerTest.testExceptionWhenFlushClusterState-seed#[3FA4603116430354]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testExceptionWhenFlushClusterState [junit4] 1> / (0) [junit4] 1> [junit4] 2> 124773 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer. [junit4] 2> 124773 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 0x100002127600000, likely client has closed socket [junit4] 2> 124784 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 80 /solr/collections/collection1/state.json [junit4] 2> 79 /solr/clusterprops.json [junit4] 2> 68 /solr/aliases.json [junit4] 2> 13 /solr/collections/collection2/state.json [junit4] 2> 5 /solr/collections/c1/state.json [junit4] 2> 4 /solr/collections/collection3/state.json [junit4] 2> 3 /solr/collections/collection0/state.json [junit4] 2> 2 /solr/collections/collection4/state.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 78 /solr/clusterstate.json [junit4] 2> 21 /solr/autoscaling.json [junit4] 2> 3 /solr/collections/test/state.json [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 79 /solr/live_nodes [junit4] 2> 70 /solr/collections [junit4] 2> 21 /solr/overseer/queue-work [junit4] 2> 20 /solr/autoscaling/events/.scheduled_maintenance [junit4] 2> 20 /solr/autoscaling/events/.auto_add_replicas [junit4] 2> 20 /solr/overseer/collection-queue-work [junit4] 2> 16 /solr/overseer/queue [junit4] 2> [junit4] 2> 124803 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50415 [junit4] 2> 124803 INFO (SUITE-OverseerTest-seed#[3FA4603116430354]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50415 [junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.OverseerTest_3FA4603116430354-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=1625, maxMBSortInHeap=6.737779911862903, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@12e81488), locale=sw, timezone=America/Bogota [junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 9.0.4 (64-bit)/cpus=3,threads=1,free=66609504,total=243867648 [junit4] 2> NOTE: All tests run in this JVM: [JsonLoaderTest, TestSkipOverseerOperations, ChaosMonkeyNothingIsSafeTest, OverseerTest] [junit4] Completed [11/843 (1!)] on J1 in 23.61s, 14 tests, 1 failure, 1 skipped <<< FAILURES! [...truncated 45424 lines...] [repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/7733/consoleText [repro] Revision: 8ac34c2d6d105ae342985b2baaa00cc6c5bf4cfd [repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" [repro] ant clean [...truncated 6 lines...] [repro] Test suites by module: [repro] solr\core [repro] OverseerTest [repro] ant compile-test [...truncated 3590 lines...] [repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.OverseerTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=3FA4603116430354 -Dtests.slow=true -Dtests.locale=sw -Dtests.timezone=America/Bogota -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [...truncated 133 lines...] [repro] Failures: [repro] 0/5 failed: org.apache.solr.cloud.OverseerTest [repro] Exiting with code 0 [...truncated 78 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org