You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2015/11/08 07:19:05 UTC
[JENKINS] Lucene-Solr-trunk-Solaris (64bit/jdk1.8.0) - Build # 172
- Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Solaris/172/
Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.OverseerTest.testOverseerStatsReset
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([73C054B779021893:D894B38BECD8BB9D]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.junit.Assert.assertEquals(Assert.java:456)
at org.apache.solr.cloud.OverseerTest.testOverseerStatsReset(OverseerTest.java:722)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1660)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:875)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822)
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:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9276 lines...]
[junit4] Suite: org.apache.solr.cloud.OverseerTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerTest_73C054B779021893-001/init-core-data-001
[junit4] 2> 41646 INFO (SUITE-OverseerTest-seed#[73C054B779021893]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false)
[junit4] 2> 41646 INFO (SUITE-OverseerTest-seed#[73C054B779021893]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 41646 INFO (SUITE-OverseerTest-seed#[73C054B779021893]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 41653 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testOverseerFailure
[junit4] 2> 41653 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 41654 INFO (Thread-89) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 41654 INFO (Thread-89) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 41755 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer start zk server on port:33478
[junit4] 2> 41755 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 41756 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 41772 INFO (zkCallback-25-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@118772f1 name:ZooKeeperConnection Watcher:127.0.0.1:33478 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 41772 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 41772 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 41776 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 41777 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 41782 INFO (zkCallback-26-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2bd61e36 name:ZooKeeperConnection Watcher:127.0.0.1:33478 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 41782 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 41783 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 41783 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 41789 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 41791 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 41794 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@277c0178 name:ZooKeeperConnection Watcher:127.0.0.1:33478/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 41794 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 41795 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 41800 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 41805 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 41807 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 41810 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 41813 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 41816 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 41820 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 41821 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 41826 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5d3de31b name:ZooKeeperConnection Watcher:127.0.0.1:33478/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 41827 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 41828 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 41834 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 41837 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node1
[junit4] 2> 41840 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 41840 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 41846 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 41852 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 41866 INFO (zkCallback-29-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@47ea6be4 name:ZooKeeperConnection Watcher:127.0.0.1:33478/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 41866 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 41867 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 41867 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 41888 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 41891 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 41895 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 41896 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94828002538291204-127.0.0.1:33478_solr-n_0000000000
[junit4] 2> 41897 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33478_solr
[junit4] 2> 41897 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 41901 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=94828002538291204-127.0.0.1:33478_solr-n_0000000000) starting
[junit4] 2> 41903 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 41907 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 41912 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 41917 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 41921 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 41926 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 41934 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 41934 INFO (OverseerCollectionConfigSetProcessor-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 41939 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 41939 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 42944 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"} current state version: 0
[junit4] 2> 42945 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 42945 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 42945 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 42948 INFO (zkCallback-29-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 42948 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 42949 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 43440 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 43448 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections, has occurred - updating...
[junit4] 2> 43448 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections, has occurred - updating...
[junit4] 2> 43448 INFO (zkCallback-29-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections, has occurred - updating...
[junit4] 2> 43456 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /collections/collection1/leader_elect/shard1/election/94828002538291203-node1_core1-n_0000000000
[junit4] 2> 43458 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/leaders/shard1
[junit4] 2> 43464 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
[junit4] 2> 43471 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"core1"} current state version: 1
[junit4] 2> 43472 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"core1"}
[junit4] 2> 43481 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "core":"core1"} current state version: 1
[junit4] 2> 43486 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 43490 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"} current state version: 1
[junit4] 2> 43491 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 43491 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator shard=shard1 is already registered
[junit4] 2> 43499 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /collections/collection1/leader_elect/shard1/election/94828002538291203-node1_core1-n_0000000001
[junit4] 2> 43500 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector was going to be leader /collections/collection1/leader_elect/shard1/election/94828002538291203-node1_core1-n_0000000001 , seq(0) /collections/collection1/leader_elect/shard1/election/94828002538291203-node1_core1-n_0000000000
[junit4] 2> 43599 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 43599 INFO (zkCallback-29-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 43600 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 43942 INFO (OverseerCollectionConfigSetProcessor-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor.amILeader(OverseerTaskProcessor.java:355)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor.run(OverseerTaskProcessor.java:172)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 43942 INFO (OverseerCollectionConfigSetProcessor-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor According to ZK I (id=94828002538291204-127.0.0.1:33478_solr-n_0000000000) am no longer a leader.
[junit4] 2> 44608 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 44615 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /collections/collection1/leader_elect/shard1/election/94828002538291203-node1_core1-n_0000000002
[junit4] 2> 44616 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector was going to be leader /collections/collection1/leader_elect/shard1/election/94828002538291203-node1_core1-n_0000000002 , seq(0) /collections/collection1/leader_elect/shard1/election/94828002538291203-node1_core1-n_0000000000
[junit4] 2> 44616 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 44617 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 44619 INFO (zkCallback-31-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@e05cc4a name:ZooKeeperConnection Watcher:127.0.0.1:33478/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 44619 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 44620 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 44620 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=94828002538291204-127.0.0.1:33478_solr-n_0000000000) closing
[junit4] 2> 44620 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 44620 INFO (OverseerStateUpdate-94828002538291204-127.0.0.1:33478_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:33478_solr
[junit4] 2> 44625 ERROR (OverseerExitThread) [ ] o.a.s.c.Overseer could not read the data
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:304)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:87)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$2.run(Overseer.java:265)
[junit4] 2> 44628 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 44629 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94828002538291205-127.0.0.1:33478_solr-n_0000000001
[junit4] 2> 44630 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33478_solr
[junit4] 2> 44630 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 44633 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=94828002538291205-127.0.0.1:33478_solr-n_0000000001) starting
[junit4] 2> 44654 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 44654 INFO (OverseerCollectionConfigSetProcessor-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 44655 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 44656 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 44664 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.Overseer processMessage: workQueueSize: 3, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"core1"}
[junit4] 2> 44664 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"core1"}
[junit4] 2> 44665 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44666 INFO (zkCallback-31-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44666 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44671 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.Overseer processMessage: workQueueSize: 3, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "core":"core1"}
[junit4] 2> 44675 INFO (zkCallback-31-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44675 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44676 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44678 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.Overseer processMessage: workQueueSize: 3, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 44679 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 44679 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.o.ReplicaMutator shard=shard1 is already registered
[junit4] 2> 44680 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44680 INFO (zkCallback-31-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44680 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44683 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"} current state version: 5
[junit4] 2> 44684 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 44684 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.o.ReplicaMutator shard=shard1 is already registered
[junit4] 2> 44799 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44800 INFO (zkCallback-31-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44801 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 44823 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"deletecore",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1"} current state version: 6
[junit4] 2> 44953 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.o.ZkStateWriter going to delete_collection /collections/collection1/state.json
[junit4] 2> 45013 INFO (zkCallback-31-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections, has occurred - updating...
[junit4] 2> 45014 INFO (zkCallback-27-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections, has occurred - updating...
[junit4] 2> 45016 INFO (zkCallback-28-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections, has occurred - updating...
[junit4] 2> 45016 INFO (zkCallback-27-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 45019 INFO (zkCallback-31-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 45020 INFO (zkCallback-28-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 45519 INFO (zkCallback-27-thread-2) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 45519 INFO (zkCallback-31-thread-2) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 45519 INFO (zkCallback-28-thread-2) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 45532 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33478 33478
[junit4] 2> 45551 INFO (Thread-89) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:33478 33478
[junit4] 2> 45554 WARN (Thread-89) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 4 /solr/collections
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> 45554 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testOverseerFailure
[junit4] 2> 45554 INFO (TEST-OverseerTest.testOverseerFailure-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=94828002538291205-127.0.0.1:33478_solr-n_0000000001) closing
[junit4] 2> 45554 INFO (OverseerStateUpdate-94828002538291205-127.0.0.1:33478_solr-n_0000000001) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:33478_solr
[junit4] 2> 45555 ERROR (OverseerExitThread) [ ] o.a.s.c.Overseer could not read the data
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:304)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:87)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$2.run(Overseer.java:265)
[junit4] 2> 45569 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDoubleAssignment
[junit4] 2> 45571 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 45571 INFO (Thread-90) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 45571 INFO (Thread-90) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 45671 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer start zk server on port:58871
[junit4] 2> 45671 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 45672 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 45676 INFO (zkCallback-33-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1c6273a2 name:ZooKeeperConnection Watcher:127.0.0.1:58871/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 45677 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 45677 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 45677 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 45678 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 45681 INFO (zkCallback-34-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@33eb2f44 name:ZooKeeperConnection Watcher:127.0.0.1:58871 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 45681 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 45681 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 45688 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 45688 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 45693 INFO (zkCallback-35-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4c79afa5 name:ZooKeeperConnection Watcher:127.0.0.1:58871 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 45693 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 45694 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 45694 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 45704 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 45708 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 45711 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 45715 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 45718 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 45720 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 45726 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 45728 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 45730 INFO (zkCallback-36-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f73c2e6 name:ZooKeeperConnection Watcher:127.0.0.1:58871/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 45730 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 45731 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 45736 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 45740 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node1
[junit4] 2> 45743 INFO (zkCallback-33-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 45743 INFO (zkCallback-36-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 45745 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 45745 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 45749 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@13901309 name:ZooKeeperConnection Watcher:127.0.0.1:58871/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 45749 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 45750 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 45750 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 45754 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 45758 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 45760 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 45761 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94828002794930180-127.0.0.1:58871_solr-n_0000000000
[junit4] 2> 45761 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:58871_solr
[junit4] 2> 45761 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 45763 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=94828002794930180-127.0.0.1:58871_solr-n_0000000000) starting
[junit4] 2> 45765 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 45768 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 45772 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 45776 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 45782 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 45787 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 45796 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 45797 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 45798 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 45797 INFO (OverseerCollectionConfigSetProcessor-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 45805 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"} current state version: 0
[junit4] 2> 45806 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 45806 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 45806 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 45809 INFO (zkCallback-33-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 45809 INFO (zkCallback-36-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 45809 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 46304 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 46308 INFO (zkCallback-33-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections, has occurred - updating...
[junit4] 2> 46308 INFO (zkCallback-36-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections, has occurred - updating...
[junit4] 2> 46309 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader A collections change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/collections, has occurred - updating...
[junit4] 2> 46319 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /collections/collection1/leader_elect/shard1/election/94828002794930179-node1_core1-n_0000000000
[junit4] 2> 46321 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/leaders/shard1
[junit4] 2> 46331 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
[junit4] 2> 46346 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"core1"} current state version: 1
[junit4] 2> 46347 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"core1"}
[junit4] 2> 46350 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 46350 INFO (zkCallback-33-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 46351 INFO (zkCallback-36-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 46356 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 46357 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 46358 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "core":"core1"} current state version: 1
[junit4] 2> 46361 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@f1c0a7e name:ZooKeeperConnection Watcher:127.0.0.1:58871/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 46361 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 46361 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 46370 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 46374 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/node1
[junit4] 2> 46376 INFO (zkCallback-33-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 46376 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 46377 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 46381 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 46391 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"} current state version: 1
[junit4] 2> 46392 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"recovering",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core":"core1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"1",
[junit4] 2> "base_url":"http://node1/solr/"}
[junit4] 2> 46392 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator shard=shard1 is already registered
[junit4] 2> 46397 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /collections/collection1/leader_elect/shard1/election/94828002794930181-node1_core1-n_0000000001
[junit4] 2> 46399 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
[junit4] 2> 46407 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"core1"} current state version: 1
[junit4] 2> 46409 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "node_name":"node1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"core1"}
[junit4] 2> 46415 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"http://node1/solr/",
[junit4] 2> "core":"core1"} current state version: 1
[junit4] 2> 46527 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 46527 INFO (zkCallback-33-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 46531 INFO (zkCallback-37-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 46543 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x150e590ed5a0004, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 46544 INFO (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 46544 INFO (zkCallback-33-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 46547 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x150e590ed5a0005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 46547 WARN (zkCallback-39-thread-1) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 46550 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58871 58871
[junit4] 2> 46587 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
[junit4] 2> java.nio.channels.CancelledKeyException
[junit4] 2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
[junit4] 2> at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:187)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 46587 INFO (Thread-90) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:58871 58871
[junit4] 2> 46593 WARN (Thread-90) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 4 /solr/live_nodes
[junit4] 2> 4 /solr/collections
[junit4] 2>
[junit4] 2> 46593 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testDoubleAssignment
[junit4] 2> 46593 INFO (TEST-OverseerTest.testDoubleAssignment-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=94828002794930180-127.0.0.1:58871_solr-n_0000000000) closing
[junit4] 2> 46594 INFO (OverseerStateUpdate-94828002794930180-127.0.0.1:58871_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:58871_solr
[junit4] 2> 46596 ERROR (OverseerExitThread) [ ] o.a.s.c.Overseer could not read the data
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:304)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:87)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$2.run(Overseer.java:265)
[junit4] 2> 46606 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStateChange
[junit4] 2> 46609 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 46610 INFO (Thread-91) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 46610 INFO (Thread-91) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 46712 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer start zk server on port:42543
[junit4] 2> 46712 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 46713 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 46724 INFO (zkCallback-40-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@127ac516 name:ZooKeeperConnection Watcher:127.0.0.1:42543/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 46725 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 46725 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 46725 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 46726 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 46729 INFO (zkCallback-41-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@622d3370 name:ZooKeeperConnection Watcher:127.0.0.1:42543 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 46729 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 46730 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 46737 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 46738 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 46741 INFO (zkCallback-42-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5e731f32 name:ZooKeeperConnection Watcher:127.0.0.1:42543 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 46741 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 46742 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 46742 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 46749 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 46752 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 46757 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 46760 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 46763 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 46765 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 46768 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 46769 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 46780 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3f1eae56 name:ZooKeeperConnection Watcher:127.0.0.1:42543/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 46780 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 46781 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 46781 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 46787 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 46790 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 46792 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 46793 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94828002862956547-127.0.0.1:42543_solr-n_0000000000
[junit4] 2> 46794 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42543_solr
[junit4] 2> 46794 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 46797 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=94828002862956547-127.0.0.1:42543_solr-n_0000000000) starting
[junit4] 2> 46800 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 46804 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 46809 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 46813 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 46816 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 46822 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 46831 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 46832 INFO (OverseerCollectionConfigSetProcessor-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 46836 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 46837 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 46851 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1/solr",
[junit4] 2> "node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"core1",
[junit4] 2> "roles":"",
[junit4] 2> "state":"recovering"} current state version: 0
[junit4] 2> 46855 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1/solr",
[junit4] 2> "node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"core1",
[junit4] 2> "roles":"",
[junit4] 2> "state":"recovering"}
[junit4] 2> 46855 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 46863 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 46863 INFO (zkCallback-40-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 46895 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1/solr",
[junit4] 2> "node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"core1",
[junit4] 2> "roles":"",
[junit4] 2> "state":"active"} current state version: 1
[junit4] 2> 46896 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "base_url":"http://127.0.0.1/solr",
[junit4] 2> "node_name":"node1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "core":"core1",
[junit4] 2> "roles":"",
[junit4] 2> "state":"active"}
[junit4] 2> 46896 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator node=core_node1 is already registered
[junit4] 2> 46896 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.o.ReplicaMutator shard=shard1 is already registered
[junit4] 2> 47002 INFO (zkCallback-40-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 47002 INFO (zkCallback-43-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 0)
[junit4] 2> 47046 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x150e590f1680000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 47060 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42543 42543
[junit4] 2> 47168 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxnFactory Ignoring unexpected runtime exception
[junit4] 2> java.nio.channels.CancelledKeyException
[junit4] 2> at sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:73)
[junit4] 2> at sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:87)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:187)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 47169 INFO (Thread-91) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42543 42543
[junit4] 2> 47172 WARN (Thread-91) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/live_nodes
[junit4] 2> 2 /solr/collections
[junit4] 2>
[junit4] 2> 47172 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStateChange
[junit4] 2> 47172 INFO (TEST-OverseerTest.testStateChange-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=94828002862956547-127.0.0.1:42543_solr-n_0000000000) closing
[junit4] 2> 47172 INFO (OverseerStateUpdate-94828002862956547-127.0.0.1:42543_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:42543_solr
[junit4] 2> 47174 ERROR (OverseerExitThread) [ ] o.a.s.c.Overseer could not read the data
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:304)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:87)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$2.run(Overseer.java:265)
[junit4] 2> 47178 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReplay
[junit4] 2> 47180 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 47180 INFO (Thread-92) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 47180 INFO (Thread-92) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 47280 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer start zk server on port:43412
[junit4] 2> 47280 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 47281 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 47288 INFO (zkCallback-45-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2fec3778 name:ZooKeeperConnection Watcher:127.0.0.1:43412/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 47288 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 47289 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 47289 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 47289 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 47292 INFO (zkCallback-46-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@61b90a9d name:ZooKeeperConnection Watcher:127.0.0.1:43412 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 47293 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 47293 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 47296 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 47296 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 47299 INFO (zkCallback-47-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5214f564 name:ZooKeeperConnection Watcher:127.0.0.1:43412 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 47300 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 47300 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 47300 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 47307 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 47310 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections
[junit4] 2> 47314 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 47317 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 47319 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 47321 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 47327 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 47332 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 47332 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 47337 INFO (zkCallback-48-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4fa52ad3 name:ZooKeeperConnection Watcher:127.0.0.1:43412/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 47337 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 47337 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 47337 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=600000&connTimeout=60000&retry=true
[junit4] 2> 47340 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 47343 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 47346 INFO (TEST-OverseerTest.testReplay-seed#[73C054B779021893]) [
[...truncated too long message...]
nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 83874 INFO (zkCallback-118-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 83875 INFO (zkCallback-119-thread-2) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4] 2> 83875 ERROR (TEST-OverseerTest.testShardAssignmentBigger-seed#[73C054B779021893]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1@6b4af8 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@454de2d[Terminated, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 14]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
[junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2> 85375 WARN (zkCallback-117-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 85378 ERROR (TEST-OverseerTest.testShardAssignmentBigger-seed#[73C054B779021893]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1@5c01f29b rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@583e7dc3[Shutting down, pool size = 1, active threads = 0, queued tasks = 0, completed tasks = 14]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
[junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2> 85380 ERROR (TEST-OverseerTest.testShardAssignmentBigger-seed#[73C054B779021893]-EventThread) [ ] o.a.z.ClientCnxn Error while calling watcher
[junit4] 2> java.util.concurrent.RejectedExecutionException: Task org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1@8934d21 rejected from org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor@4d91d257[Shutting down, pool size = 2, active threads = 0, queued tasks = 0, completed tasks = 16]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$AbortPolicy.rejectedExecution(ThreadPoolExecutor.java:2047)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.reject(ThreadPoolExecutor.java:823)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1369)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.execute(ExecutorUtil.java:214)
[junit4] 2> at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3.process(SolrZkClient.java:266)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:522)
[junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:498)
[junit4] 2> 85381 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[73C054B779021893]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50598 50598
[junit4] 2> 85474 INFO (Thread-100) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:50598 50598
[junit4] 2> 85474 WARN (Thread-100) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/clusterstate.json
[junit4] 2> 4 /solr/collections/collection1/leader_elect/shard2/election/94828004804132878-node10_core10-n_0000000005
[junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/94828004804132873-node5_core5-n_0000000005
[junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/94828004804132875-node7_core7-n_0000000001
[junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/94828004804132876-node8_core8-n_0000000004
[junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/94828004804132870-node2_core2-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/live_nodes
[junit4] 2> 14 /solr/collections
[junit4] 2>
[junit4] 2> 85475 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[73C054B779021893]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testShardAssignmentBigger
[junit4] 2> 85475 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[73C054B779021893]) [ ] o.a.s.c.Overseer Overseer (id=94828004804132867-127.0.0.1:50598_solr-n_0000000000) closing
[junit4] 2> 85476 INFO (OverseerStateUpdate-94828004804132867-127.0.0.1:50598_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:50598_solr
[junit4] 2> 85476 ERROR (OverseerExitThread) [ ] o.a.s.c.Overseer could not read the data
[junit4] 2> org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer_elect/leader
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1155)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:353)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$7.execute(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:350)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.checkIfIamStillLeader(Overseer.java:304)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:87)
[junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$2.run(Overseer.java:265)
[junit4] 2> 88476 INFO (SUITE-OverseerTest-seed#[73C054B779021893]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J0/temp/solr.cloud.OverseerTest_73C054B779021893-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {}, docValues:{}, sim=ClassicSimilarity, locale=ar_EG, timezone=Africa/Dakar
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=3,threads=1,free=167515760,total=214958080
[junit4] 2> NOTE: All tests run in this JVM: [ReplicationFactorTest, DeleteShardTest, TestEmbeddedSolrServerConstructors, AnalyticsQueryTest, TestReplicationHandlerBackup, SampleTest, OverseerTest]
[junit4] Completed [16/555] on J0 in 46.85s, 12 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 1745 lines...]
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:775: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:719: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:59: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build.xml:233: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/common-build.xml:516: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:1432: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:987: There were test failures: 555 suites (8 ignored), 2200 tests, 1 failure, 81 ignored (67 assumptions) [seed: 73C054B779021893]
Total time: 73 minutes 58 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-Solaris (64bit/jdk1.8.0) - Build # 174
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Solaris/174/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
Error from server at http://127.0.0.1:64233/awholynewcollection_0: Expected mime type application/octet-stream but got text/html. <html> <head> <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/> <title>Error 500 </title> </head> <body> <h2>HTTP ERROR: 500</h2> <p>Problem accessing /awholynewcollection_0/select. Reason: <pre> {trace=java.lang.NullPointerException at org.apache.solr.servlet.HttpSolrCall.getCoreByCollection(HttpSolrCall.java:786) at org.apache.solr.servlet.HttpSolrCall.init(HttpSolrCall.java:270) at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:415) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:179) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) at org.eclipse.jetty.server.Server.handle(Server.java:499) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257) at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555) at java.lang.Thread.run(Thread.java:745) ,code=500}</pre></p> <hr /><i><small>Powered by Jetty://</small></i> </body> </html>
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:64233/awholynewcollection_0: Expected mime type application/octet-stream but got text/html. <html>
<head>
<meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
<title>Error 500 </title>
</head>
<body>
<h2>HTTP ERROR: 500</h2>
<p>Problem accessing /awholynewcollection_0/select. Reason:
<pre> {trace=java.lang.NullPointerException
at org.apache.solr.servlet.HttpSolrCall.getCoreByCollection(HttpSolrCall.java:786)
at org.apache.solr.servlet.HttpSolrCall.init(HttpSolrCall.java:270)
at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:415)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220)
at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:179)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
at org.eclipse.jetty.server.Server.handle(Server.java:499)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
at java.lang.Thread.run(Thread.java:745)
,code=500}</pre></p>
<hr /><i><small>Powered by Jetty://</small></i>
</body>
</html>
at __randomizedtesting.SeedInfo.seed([69748AE4EE96B9B9:E120B53E406AD441]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:543)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:150)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForNon403or404or503(AbstractFullDistribZkTestBase.java:1754)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:665)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:167)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1660)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:875)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822)
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:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 9750 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> Creating dataDir: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/init-core-data-001
[junit4] 2> 1147195 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[69748AE4EE96B9B9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true)
[junit4] 2> 1147196 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[69748AE4EE96B9B9]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 1147198 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1147198 INFO (Thread-5447) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1147198 INFO (Thread-5447) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1147298 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.ZkTestServer start zk server on port:42484
[junit4] 2> 1147298 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1147299 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1147302 INFO (zkCallback-924-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a40576f name:ZooKeeperConnection Watcher:127.0.0.1:42484 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1147302 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1147302 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1147302 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr
[junit4] 2> 1147306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1147307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1147308 INFO (zkCallback-925-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2d4220ed name:ZooKeeperConnection Watcher:127.0.0.1:42484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1147308 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1147309 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1147309 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1
[junit4] 2> 1147311 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards
[junit4] 2> 1147314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection
[junit4] 2> 1147316 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards
[junit4] 2> 1147320 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1147320 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1147326 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1147326 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml
[junit4] 2> 1147330 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1147330 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1147334 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1147334 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1147337 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1147337 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt
[junit4] 2> 1147341 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1147341 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml
[junit4] 2> 1147344 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1147345 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1147348 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1147348 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1147363 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1147364 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1147367 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1147367 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1147369 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractZkTestCase put /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1147369 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1147547 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1
[junit4] 2> 1147550 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1147552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2039156a{/,null,AVAILABLE}
[junit4] 2> 1147555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.e.j.s.ServerConnector Started ServerConnector@97e8ae6{HTTP/1.1}{127.0.0.1:55605}
[junit4] 2> 1147555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.e.j.s.Server Started @1151925ms
[junit4] 2> 1147555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/tempDir-001/control/data, hostContext=/, hostPort=55605, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores}
[junit4] 2> 1147555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@659e0bfd
[junit4] 2> 1147555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/'
[junit4] 2> 1147555 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1147556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1147578 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1147579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1147582 INFO (zkCallback-926-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@233c254a name:ZooKeeperConnection Watcher:127.0.0.1:42484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1147582 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1147582 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1147585 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1147585 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/solr.xml
[junit4] 2> 1147590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores
[junit4] 2> 1147590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.CoreContainer New CoreContainer 918823116
[junit4] 2> 1147590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/]
[junit4] 2> 1147590 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.CoreContainer loading shared library: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/lib
[junit4] 2> 1147590 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/lib).
[junit4] 2> 1147597 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1147618 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1147619 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1147619 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1147619 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42484/solr
[junit4] 2> 1147619 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1147619 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1147620 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1147622 INFO (zkCallback-928-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3c04613a name:ZooKeeperConnection Watcher:127.0.0.1:42484 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1147623 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1147623 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1147626 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1147627 INFO (zkCallback-929-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@67f4dfc3 name:ZooKeeperConnection Watcher:127.0.0.1:42484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1147628 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1147629 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue
[junit4] 2> 1147632 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work
[junit4] 2> 1147640 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running
[junit4] 2> 1147643 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed
[junit4] 2> 1147647 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure
[junit4] 2> 1147650 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes
[junit4] 2> 1147653 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json
[junit4] 2> 1147655 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json
[junit4] 2> 1147657 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /security.json
[junit4] 2> 1147658 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55605_
[junit4] 2> 1147659 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:55605_
[junit4] 2> 1147661 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect
[junit4] 2> 1147667 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election
[junit4] 2> 1147669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1147670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94832137279111172-127.0.0.1:55605_-n_0000000000
[junit4] 2> 1147670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55605_
[junit4] 2> 1147670 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader
[junit4] 2> 1147672 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.Overseer Overseer (id=94832137279111172-127.0.0.1:55605_-n_0000000000) starting
[junit4] 2> 1147675 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work
[junit4] 2> 1147685 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 1147686 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1147696 INFO (OverseerCollectionConfigSetProcessor-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations
[junit4] 2> 1147701 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.Overseer Starting to work on the main queue
[junit4] 2> 1147708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1147708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1147710 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores
[junit4] 2> 1147711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1147711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.CoresLocator Found core collection1 in /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/
[junit4] 2> 1147711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1147716 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1147716 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 1147719 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55605",
[junit4] 2> "node_name":"127.0.0.1:55605_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1147720 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55605",
[junit4] 2> "node_name":"127.0.0.1:55605_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1147720 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection
[junit4] 2> 1147721 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1
[junit4] 2> 1147723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.s.SolrDispatchFilter user.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1
[junit4] 2> 1147723 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:55605_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1147724 INFO (zkCallback-929-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1147727 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1147727 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1
[junit4] 2> 1147727 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection
[junit4] 2> 1147729 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists
[junit4] 2> 1147729 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
[junit4] 2> 1147730 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1147730 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/'
[junit4] 2> 1147731 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1147731 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1147790 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0
[junit4] 2> 1147795 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1
[junit4] 2> 1147798 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0
[junit4] 2> 1147804 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1147806 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 1147810 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test
[junit4] 2> 1147875 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 1147880 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text
[junit4] 2> 1147881 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id
[junit4] 2> 1147894 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1147896 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml
[junit4] 2> 1147898 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1147899 WARN (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1147899 WARN (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1147900 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 1147901 WARN (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE
[junit4] 2> 1147901 WARN (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING
[junit4] 2> 1147902 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1
[junit4] 2> 1147902 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 1147902 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1147902 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/], dataDir=[null]
[junit4] 2> 1147902 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@51b04cf5
[junit4] 2> 1147902 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/data
[junit4] 2> 1147902 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/data/index/
[junit4] 2> 1147903 WARN (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 1147903 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/data/index
[junit4] 2> 1147903 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=29, maxMergeAtOnceExplicit=32, maxMergedSegmentMB=10.9619140625, floorSegmentMB=1.2080078125, forceMergeDeletesPctAllowed=1.6645301170486615, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.12408679144232684
[junit4] 2> 1147903 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@55acaf8d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d346c25),segFN=segments_1,generation=1}
[junit4] 2> 1147903 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1147904 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Looking for old index directories to cleanup for core collection1 in /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/data/
[junit4] 2> 1147904 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories.
[junit4] 2> 1147907 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 1147908 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe"
[junit4] 2> 1147908 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 1147908 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 1147908 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 1147908 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 1147908 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1147908 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 1147908 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 1147908 WARN (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}}
[junit4] 2> 1147910 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1147911 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1147912 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1147912 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60
[junit4] 2> 1147915 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 1147915 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 1147916 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1147916 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1147936 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled
[junit4] 2> 1147937 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled
[junit4] 2> 1147937 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=19.8916015625, floorSegmentMB=1.1015625, forceMergeDeletesPctAllowed=11.603716790977138, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0
[junit4] 2> 1147937 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@55acaf8d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@d346c25),segFN=segments_1,generation=1}
[junit4] 2> 1147937 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1
[junit4] 2> 1147937 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6ad11ef2[collection1] main
[junit4] 2> 1147937 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection
[junit4] 2> 1147939 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 1147939 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 1147940 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 1147940 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {}
[junit4] 2> 1147940 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1147940 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 1147940 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 1147940 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources
[junit4] 2> 1147940 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000
[junit4] 2> 1147941 INFO (searcherExecutor-3201-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6ad11ef2[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1147941 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Looking up max value of version field to seed version buckets
[junit4] 2> 1147941 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index
[junit4] 2> 1147941 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo No terms found for _version_, cannot seed version bucket highest value from index
[junit4] 2> 1147941 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1517314197242773504
[junit4] 2> 1147943 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1517314197242773504
[junit4] 2> 1147943 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1
[junit4] 2> 1147944 INFO (coreLoadExecutor-3200-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1
[junit4] 2> 1147944 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:55605 collection:control_collection shard:shard1
[junit4] 2> 1147946 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 1147951 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.LeaderElector Joined leadership election with path: /collections/control_collection/leader_elect/shard1/election/94832137279111172-core_node1-n_0000000000
[junit4] 2> 1147951 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1
[junit4] 2> 1147954 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1147954 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1147954 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55605/collection1/
[junit4] 2> 1147954 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1147954 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:55605/collection1/ has no replicas
[junit4] 2> 1147954 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:55605/collection1/ shard1
[junit4] 2> 1147954 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 1147955 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 1147958 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node
[junit4] 2> 1147962 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:55605",
[junit4] 2> "node_name":"127.0.0.1:55605_",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1"} current state version: 1
[junit4] 2> 1147963 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:55605",
[junit4] 2> "node_name":"127.0.0.1:55605_",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "core":"collection1"}
[junit4] 2> 1147966 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:55605",
[junit4] 2> "core":"collection1"} current state version: 1
[junit4] 2> 1148069 INFO (zkCallback-929-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1148116 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:55605/collection1/ and leader is http://127.0.0.1:55605/collection1/
[junit4] 2> 1148116 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:55605
[junit4] 2> 1148116 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1148116 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active
[junit4] 2> 1148116 INFO (coreZkRegister-3194-thread-1-processing-n:127.0.0.1:55605_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55605_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 1148119 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55605",
[junit4] 2> "node_name":"127.0.0.1:55605_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 2
[junit4] 2> 1148120 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:55605",
[junit4] 2> "node_name":"127.0.0.1:55605_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 1148225 INFO (zkCallback-929-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1148226 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1148226 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1148228 INFO (zkCallback-931-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@393781de name:ZooKeeperConnection Watcher:127.0.0.1:42484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1148228 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1148228 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1148228 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1148231 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 1148362 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/cores/collection1
[junit4] 2> 1148363 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001
[junit4] 2> 1148363 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730
[junit4] 2> 1148366 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2d8abe16{/,null,AVAILABLE}
[junit4] 2> 1148366 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.e.j.s.ServerConnector Started ServerConnector@236a868c{HTTP/1.1}{127.0.0.1:34030}
[junit4] 2> 1148366 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.e.j.s.Server Started @1152737ms
[junit4] 2> 1148366 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=34030, coreRootDirectory=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/cores}
[junit4] 2> 1148367 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@659e0bfd
[junit4] 2> 1148367 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/'
[junit4] 2> 1148367 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1148367 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 1148402 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1148403 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1148405 INFO (zkCallback-932-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@20ae7f43 name:ZooKeeperConnection Watcher:127.0.0.1:42484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1148405 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1148405 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1148407 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 1148407 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/solr.xml
[junit4] 2> 1148411 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/cores
[junit4] 2> 1148411 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.CoreContainer New CoreContainer 1189362405
[junit4] 2> 1148411 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/]
[junit4] 2> 1148412 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.CoreContainer loading shared library: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/lib
[junit4] 2> 1148412 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/lib).
[junit4] 2> 1148419 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.h.c.HttpShardHandlerFactory created with socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost : 20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize : 2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy : false,useRetries : false,
[junit4] 2> 1148420 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 1148420 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 1148420 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 1148421 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42484/solr
[junit4] 2> 1148421 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.ZkController zkHost includes chroot
[junit4] 2> 1148421 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider
[junit4] 2> 1148421 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1148423 INFO (zkCallback-934-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7b906257 name:ZooKeeperConnection Watcher:127.0.0.1:42484 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1148424 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1148424 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider
[junit4] 2> 1148426 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1148428 INFO (zkCallback-935-thread-1-processing-n:127.0.0.1:34030_) [n:127.0.0.1:34030_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@b72e5a2 name:ZooKeeperConnection Watcher:127.0.0.1:42484/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1148428 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1148436 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper...
[junit4] 2> 1149444 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34030_
[junit4] 2> 1149445 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:34030_
[junit4] 2> 1149447 INFO (zkCallback-931-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1149447 INFO (zkCallback-929-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1149447 INFO (zkCallback-935-thread-1-processing-n:127.0.0.1:34030_) [n:127.0.0.1:34030_ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1149450 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 1149450 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94832137279111176-127.0.0.1:34030_-n_0000000001
[junit4] 2> 1149451 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94832137279111172-127.0.0.1:55605_-n_0000000000 to know if I could be the leader
[junit4] 2> 1149462 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module.
[junit4] 2> 1149463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.CoreContainer No authentication plugin used.
[junit4] 2> 1149463 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/cores
[junit4] 2> 1149464 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=}
[junit4] 2> 1149464 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.CoresLocator Found core collection1 in /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/../../../../../../../../../../export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001/shard-1-001/cores/collection1/
[junit4] 2> 1149464 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.c.CoresLocator Found 1 core definitions
[junit4] 2> 1149466 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.s.SolrDispatchFilter user.dir=/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1
[junit4] 2> 1149466 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[69748AE4EE96B9B9]) [n:127.0.0.1:34030_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done
[junit4] 2> 1149466 INFO (coreLoadExecutor-3211-thread-1-processing-n:127.0.0.1:34030_) [n:127.0.0.1:34030_ c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down
[junit4] 2> 1149466 INFO (coreLoadExecutor-3211-thread-1-processing-n:127.0.0.1:34030_) [n:127.0.0.1:34030_ c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property
[junit4] 2> 1149468 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:34030",
[junit4] 2> "node_name":"127.0.0.1:34030_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 1149469 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:34030",
[junit4] 2> "node_name":"127.0.0.1:34030_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 1149469 INFO (coreLoadExecutor-3211-thread-1-processing-n:127.0.0.1:34030_) [n:127.0.0.1:34030_ c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name
[junit4] 2> 1149469 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1
[junit4] 2> 1149469 INFO (OverseerStateUpdate-94832137279111172-127.0.0.1:55605_-n_0000000000) [n:127.0.0.1:55605_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2
[junit4] 2> 1149572 INFO (zkCallback-929-thread-1-processing-n:127.0.0.1:55605_) [n:127.0.0.1:55605_ ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 114
[...truncated too long message...]
lrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:64233/awholynewcollection_0: Expected mime type application/octet-stream but got text/html. <html>
[junit4] > <head>
[junit4] > <meta http-equiv="Content-Type" content="text/html;charset=ISO-8859-1"/>
[junit4] > <title>Error 500 </title>
[junit4] > </head>
[junit4] > <body>
[junit4] > <h2>HTTP ERROR: 500</h2>
[junit4] > <p>Problem accessing /awholynewcollection_0/select. Reason:
[junit4] > <pre> {trace=java.lang.NullPointerException
[junit4] > at org.apache.solr.servlet.HttpSolrCall.getCoreByCollection(HttpSolrCall.java:786)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.init(HttpSolrCall.java:270)
[junit4] > at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:415)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:220)
[junit4] > at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:179)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:109)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83)
[junit4] > at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1127)
[junit4] > at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515)
[junit4] > at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] > at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1061)
[junit4] > at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] > at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97)
[junit4] > at org.eclipse.jetty.server.Server.handle(Server.java:499)
[junit4] > at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:310)
[junit4] > at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:257)
[junit4] > at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:635)
[junit4] > at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:555)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > ,code=500}</pre></p>
[junit4] > <hr /><i><small>Powered by Jetty://</small></i>
[junit4] > </body>
[junit4] > </html>
[junit4] > at __randomizedtesting.SeedInfo.seed([69748AE4EE96B9B9:E120B53E406AD441]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:543)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:150)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:943)
[junit4] > at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:958)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForNon403or404or503(AbstractFullDistribZkTestBase.java:1754)
[junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:665)
[junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:167)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> 1184760 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[69748AE4EE96B9B9]-worker) [n:127.0.0.1:64213_ c:nodes_used_collection s:shard2 r:core_node2 x:nodes_used_collection_shard2_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_69748AE4EE96B9B9-001
[junit4] 2> Nov 08, 2015 11:00:16 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=FastCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST, chunkSize=4, maxDocsPerChunk=10, blockSize=595), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST, chunkSize=4, blockSize=595)), sim=RandomSimilarityProvider(queryNorm=false,coord=yes): {}, locale=in_ID, timezone=America/Miquelon
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=3,threads=1,free=127356384,total=525336576
[junit4] 2> NOTE: All tests run in this JVM: [TestAtomicUpdateErrorCases, RuleEngineTest, TestConfigSetImmutable, TestZkChroot, StressHdfsTest, TestCopyFieldCollectionResource, OutputWriterTest, CollectionStateFormat2Test, TestCloudInspectUtil, ResponseLogComponentTest, TestCollationField, OverseerRolesTest, DebugComponentTest, SpellCheckCollatorTest, HdfsWriteToMultipleCollectionsTest, CdcrRequestHandlerTest, SpellPossibilityIteratorTest, TestManagedSchemaDynamicFieldResource, TestHighFrequencyDictionaryFactory, MinimalSchemaTest, BinaryUpdateRequestHandlerTest, TestFuzzyAnalyzedSuggestions, TestSchemaNameResource, ShowFileRequestHandlerTest, CachingDirectoryFactoryTest, TestPivotHelperCode, FullHLLTest, TestSimpleTrackingShardHandler, TestPKIAuthenticationPlugin, PluginInfoTest, EnumFieldTest, TestJoin, SmileWriterTest, SuggesterWFSTTest, TestSystemIdResolver, SolrIndexSplitterTest, SolrTestCaseJ4Test, TestRecoveryHdfs, TestNoOpRegenerator, RemoteQueryErrorTest, TestDistributedGrouping, SparseHLLTest, TestComplexPhraseQParserPlugin, TestSchemaVersionResource, TestValueSourceCache, TestOmitPositions, TestFastOutputStream, TestRandomRequestDistribution, ResponseHeaderTest, TestStressUserVersions, HdfsDirectoryTest, OpenExchangeRatesOrgProviderTest, TestIntervalFaceting, NoCacheHeaderTest, TestMiniSolrCloudClusterBase, RollingRestartTest, TestNamedUpdateProcessors, CSVRequestHandlerTest, TestFiltering, DistributedIntervalFacetingTest, DistributedQueryComponentOptimizationTest, TriLevelCompositeIdRoutingTest, DeleteLastCustomShardedReplicaTest, LeaderElectionIntegrationTest, ShardRoutingCustomTest, DistributedFacetPivotLargeTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestRemoveLastDynamicCopyField, TestInfoStreamLogging, TestDistributedMissingSort, HdfsThreadLeakTest, BufferStoreTest, TestBinaryResponseWriter, LeaderInitiatedRecoveryOnShardRestartTest, TestRTGBase, TestLeaderInitiatedRecoveryThread, TestReloadAndDeleteDocs, SuggesterTSTTest, TestRestoreCore, CdcrVersionReplicationTest, OpenCloseCoreStressTest, TestCoreDiscovery, FieldMutatingUpdateProcessorTest, DirectSolrConnectionTest, TestSQLHandler, SolrInfoMBeanTest, TestBinaryField, TestRebalanceLeaders, TestQuerySenderListener, TestCollectionAPI, CollectionReloadTest, CopyFieldTest, TestObjectReleaseTracker, TestRandomFaceting, TestStressRecovery, TestBadConfig, TestSolr4Spatial2, HLLUtilTest, BasicDistributedZk2Test, DistributedFacetPivotSmallTest, TestConfigOverlay, TestImplicitCoreProperties, TestShardHandlerFactory, DistributedDebugComponentTest, RAMDirectoryFactoryTest, NumericFieldsTest, SuggesterTest, CursorMarkTest, TestSimpleQParserPlugin, SuggestComponentContextFilterQueryTest, CollectionsAPIDistributedZkTest]
[junit4] Completed [210/555] on J1 in 38.10s, 1 test, 1 error <<< FAILURES!
[...truncated 1122 lines...]
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:775: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:719: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:59: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/build.xml:233: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/solr/common-build.xml:516: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:1432: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:987: There were test failures: 555 suites (8 ignored), 2200 tests, 1 error, 81 ignored (67 assumptions) [seed: 69748AE4EE96B9B9]
Total time: 72 minutes 55 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
[JENKINS] Lucene-Solr-trunk-Solaris (64bit/jdk1.8.0) - Build # 173
- Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Solaris/173/
Java: 64bit/jdk1.8.0 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.lucene.index.TestExitableDirectoryReader.testExitableFilterIndexReader
Error Message:
The request took too long to iterate over terms. Timeout: timeoutAt: 442983558272075 (System.nanoTime(): 442991311409087), TermsEnum=org.apache.lucene.codecs.bloom.BloomFilteringPostingsFormat$BloomFilteredFieldsProducer$BloomFilteredTermsEnum@61fe5e73
Stack Trace:
org.apache.lucene.index.ExitableDirectoryReader$ExitingReaderException: The request took too long to iterate over terms. Timeout: timeoutAt: 442983558272075 (System.nanoTime(): 442991311409087), TermsEnum=org.apache.lucene.codecs.bloom.BloomFilteringPostingsFormat$BloomFilteredFieldsProducer$BloomFilteredTermsEnum@61fe5e73
at __randomizedtesting.SeedInfo.seed([60826F23666B7F44:D8E7C2620DB1F6BD]:0)
at org.apache.lucene.index.ExitableDirectoryReader$ExitableTermsEnum.checkAndThrow(ExitableDirectoryReader.java:173)
at org.apache.lucene.index.ExitableDirectoryReader$ExitableTermsEnum.<init>(ExitableDirectoryReader.java:163)
at org.apache.lucene.index.ExitableDirectoryReader$ExitableTerms.iterator(ExitableDirectoryReader.java:147)
at org.apache.lucene.index.FilterLeafReader$FilterTerms.iterator(FilterLeafReader.java:113)
at org.apache.lucene.index.TestExitableDirectoryReader$TestReader$TestTerms.iterator(TestExitableDirectoryReader.java:58)
at org.apache.lucene.index.Terms.intersect(Terms.java:72)
at org.apache.lucene.util.automaton.CompiledAutomaton.getTermsEnum(CompiledAutomaton.java:336)
at org.apache.lucene.search.AutomatonQuery.getTermsEnum(AutomatonQuery.java:107)
at org.apache.lucene.search.MultiTermQuery.getTermsEnum(MultiTermQuery.java:304)
at org.apache.lucene.search.MultiTermQueryConstantScoreWrapper$1.rewrite(MultiTermQueryConstantScoreWrapper.java:145)
at org.apache.lucene.search.MultiTermQueryConstantScoreWrapper$1.bulkScorer(MultiTermQueryConstantScoreWrapper.java:198)
at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:667)
at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:474)
at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:593)
at org.apache.lucene.search.IndexSearcher.searchAfter(IndexSearcher.java:451)
at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:462)
at org.apache.lucene.index.TestExitableDirectoryReader.testExitableFilterIndexReader(TestExitableDirectoryReader.java:127)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1660)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:866)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:902)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:916)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:875)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:777)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:811)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:822)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 1459 lines...]
[junit4] Suite: org.apache.lucene.index.TestExitableDirectoryReader
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestExitableDirectoryReader -Dtests.method=testExitableFilterIndexReader -Dtests.seed=60826F23666B7F44 -Dtests.slow=true -Dtests.locale=lt -Dtests.timezone=Asia/Tokyo -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 8.86s J1 | TestExitableDirectoryReader.testExitableFilterIndexReader <<<
[junit4] > Throwable #1: org.apache.lucene.index.ExitableDirectoryReader$ExitingReaderException: The request took too long to iterate over terms. Timeout: timeoutAt: 442983558272075 (System.nanoTime(): 442991311409087), TermsEnum=org.apache.lucene.codecs.bloom.BloomFilteringPostingsFormat$BloomFilteredFieldsProducer$BloomFilteredTermsEnum@61fe5e73
[junit4] > at __randomizedtesting.SeedInfo.seed([60826F23666B7F44:D8E7C2620DB1F6BD]:0)
[junit4] > at org.apache.lucene.index.ExitableDirectoryReader$ExitableTermsEnum.checkAndThrow(ExitableDirectoryReader.java:173)
[junit4] > at org.apache.lucene.index.ExitableDirectoryReader$ExitableTermsEnum.<init>(ExitableDirectoryReader.java:163)
[junit4] > at org.apache.lucene.index.ExitableDirectoryReader$ExitableTerms.iterator(ExitableDirectoryReader.java:147)
[junit4] > at org.apache.lucene.index.FilterLeafReader$FilterTerms.iterator(FilterLeafReader.java:113)
[junit4] > at org.apache.lucene.index.TestExitableDirectoryReader$TestReader$TestTerms.iterator(TestExitableDirectoryReader.java:58)
[junit4] > at org.apache.lucene.index.Terms.intersect(Terms.java:72)
[junit4] > at org.apache.lucene.util.automaton.CompiledAutomaton.getTermsEnum(CompiledAutomaton.java:336)
[junit4] > at org.apache.lucene.search.AutomatonQuery.getTermsEnum(AutomatonQuery.java:107)
[junit4] > at org.apache.lucene.search.MultiTermQuery.getTermsEnum(MultiTermQuery.java:304)
[junit4] > at org.apache.lucene.search.MultiTermQueryConstantScoreWrapper$1.rewrite(MultiTermQueryConstantScoreWrapper.java:145)
[junit4] > at org.apache.lucene.search.MultiTermQueryConstantScoreWrapper$1.bulkScorer(MultiTermQueryConstantScoreWrapper.java:198)
[junit4] > at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:667)
[junit4] > at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:474)
[junit4] > at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:593)
[junit4] > at org.apache.lucene.search.IndexSearcher.searchAfter(IndexSearcher.java:451)
[junit4] > at org.apache.lucene.search.IndexSearcher.search(IndexSearcher.java:462)
[junit4] > at org.apache.lucene.index.TestExitableDirectoryReader.testExitableFilterIndexReader(TestExitableDirectoryReader.java:127)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {default=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128)))}, docValues:{}, sim=ClassicSimilarity, locale=lt, timezone=Asia/Tokyo
[junit4] 2> NOTE: SunOS 5.11 amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=3,threads=1,free=230544008,total=284172288
[junit4] 2> NOTE: All tests run in this JVM: [TestStressAdvance, TestBooleanMinShouldMatch, TestFieldMaskingSpanQuery, TestIntArrayDocIdSet, TestSearcherManager, TestUniqueTermCount, TestLogMergePolicy, TestNRTThreads, TestMultiTermConstantScore, TestLucene53NormsFormat, TestVirtualMethod, TestReadOnlyIndex, TestSegmentMerger, TestTotalHitCountCollector, TestCachingTokenFilter, TestMultiMMap, TestDocValuesIndexing, TestLucene50TermVectorsFormat, TestMathUtil, TestNumericRangeQuery32, TestSynonymQuery, TestIndexWriterUnicode, TestForceMergeForever, TestIndexInput, TestLucene54DocValuesFormat, TestMixedCodecs, TestSearchForDuplicates, TestToken, TestNIOFSDirectory, TestSloppyMath, TestDocumentWriter, TestIndexWriterOnJRECrash, TestCustomNorms, TestSpans, TestMatchAllDocsQuery, TestParallelLeafReader, TestSnapshotDeletionPolicy, TestIndexWriterCommit, TestSpanExplanationsOfNonMatches, TestOmitPositions, TestPerSegmentDeletes, TestLevenshteinAutomata, TestNumericUtils, TestMaxPosition, TestBooleanScorer, TestRegexpRandom, TestSpanNotQuery, TestIndexWriterMergePolicy, TestConjunctions, TestSegmentInfos, TestPrefixQuery, TestIOUtils, TestDocValues, TestFieldReuse, TestWildcard, TestDocBoost, TestMultiThreadTermVectors, TestBKD, TestSetOnce, TestReaderClosed, TestDuelingCodecsAtNight, TestRegExp, TestOmitTf, TestAutomatonQuery, TestGrowableByteArrayDataOutput, TestFuzzyQuery, TestTopFieldCollector, TestIndexFileDeleter, TestHugeRamFile, TestDocument, TestPrefixCodedTerms, TestNRTCachingDirectory, TestUsageTrackingFilterCachingPolicy, TestIndexWriterReader, TestAssertions, TestPhraseQuery, TestManyFields, TestUTF32ToUTF8, TestInPlaceMergeSorter, TestThreadedForceMerge, TestCharsRef, TestCrash, TestMultiPhraseQuery, TestPackedInts, TestIndexWriterNRTIsCurrent, TestIndexWriterDeleteByQuery, TestNumericRangeQuery64, TestBooleanOr, TestFieldValueQuery, TestSimilarityProvider, TestBooleanCoord, TestStressIndexing, TestForTooMuchCloning, TestPayloadsOnVectors, TestFieldsReader, TestLongPostings, TestSameScoresWithThreads, TestMatchNoDocsQuery, TestNotDocIdSet, TestComplexExplanations, TestParallelCompositeReader, TestPagedBytes, TestMergeSchedulerExternal, TestSloppyPhraseQuery2, TestDisjunctionMaxQuery, TestTermRangeQuery, TestLiveFieldValues, TestNoDeletionPolicy, TestTermVectorsReader, TestTopDocsCollector, TestSegmentTermEnum, TestParallelReaderEmptyIndex, TestFileSwitchDirectory, TestCheckIndex, TestSpanMultiTermQueryWrapper, TestCompiledAutomaton, TestBinaryTerms, TestIndexWriterConfig, TestPositionIncrement, TestSimilarity, TestNumericTokenStream, TestSearch, TestPriorityQueue, TestDocCount, TestBinaryDocument, TestRecyclingByteBlockAllocator, TestIsCurrent, TestIndexWriterLockRelease, TestByteBlockPool, TestDemo, TestTwoPhaseCommitTool, TestCloseableThreadLocal, Test4GBStoredFields, TestNGramPhraseQuery, TestSimpleAttributeImpl, TestTerm, TestNewestSegment, Test2BNumericDocValues, TestReusableStringReader, TestBlockPostingsFormat2, TestLucene50SegmentInfoFormat, TestLucene50StoredFieldsFormat, Test2BSortedDocValuesOrds, TestBinaryDocValuesUpdates, TestCodecUtil, TestDimensionalValues, TestDocInverterPerFieldErrorInfo, TestExceedMaxTermLength, TestExitableDirectoryReader]
[junit4] Completed [339/400] on J1 in 8.88s, 1 test, 1 error <<< FAILURES!
[...truncated 208 lines...]
BUILD FAILED
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:775: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:719: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/build.xml:59: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/build.xml:50: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:1432: The following error occurred while executing this line:
/export/home/jenkins/workspace/Lucene-Solr-trunk-Solaris/lucene/common-build.xml:987: There were test failures: 400 suites (1 ignored), 3269 tests, 1 error, 51 ignored (47 assumptions) [seed: 60826F23666B7F44]
Total time: 6 minutes 23 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any