Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/944/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=15649, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=15649, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:39439: collection already exists: awholynewstresscollection_collection1_3 at __randomizedtesting.SeedInfo.seed([AA5A12016F43EF92]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) 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.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1593) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1614) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:973) FAILED: org.apache.solr.cloud.OverseerTest.testShardLeaderChange Error Message: Could not register as the leader because creating the ephemeral registration node in ZooKeeper failed Stack Trace: org.apache.solr.common.SolrException: Could not register as the leader because creating the ephemeral registration node in ZooKeeper failed at __randomizedtesting.SeedInfo.seed([AA5A12016F43EF92:740995F675DB1A63]:0) at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:212) at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:173) at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:138) at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:310) at org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:219) at org.apache.solr.cloud.OverseerTest$MockZKController.publishState(OverseerTest.java:181) at org.apache.solr.cloud.OverseerTest.testShardLeaderChange(OverseerTest.java:841) 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:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java: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:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.zookeeper.KeeperException$NodeExistsException: KeeperErrorCode = NodeExists at org.apache.zookeeper.KeeperException.create(KeeperException.java:119) at org.apache.zookeeper.ZooKeeper.multiInternal(ZooKeeper.java:949) at org.apache.zookeeper.ZooKeeper.multi(ZooKeeper.java:915) at org.apache.solr.common.cloud.SolrZkClient$11.execute(SolrZkClient.java:577) at org.apache.solr.common.cloud.SolrZkClient$11.execute(SolrZkClient.java:574) at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60) at org.apache.solr.common.cloud.SolrZkClient.multi(SolrZkClient.java:574) at org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:195) at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:49) at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:42) at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:178) ... 45 more Build Log: [...truncated 11597 lines...] [junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/init-core-data-001 [junit4] 2> 1779174 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[AA5A12016F43EF92]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) [junit4] 2> 1779174 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[AA5A12016F43EF92]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 2> 1779176 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1779177 INFO (Thread-6919) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1779178 INFO (Thread-6919) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1779277 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.ZkTestServer start zk server on port:53851 [junit4] 2> 1779277 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1779285 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1779288 INFO (zkCallback-2271-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7b7ef7e1 name:ZooKeeperConnection Watcher:127.0.0.1:53851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1779288 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1779288 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1779288 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1779291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1779293 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1779294 INFO (zkCallback-2272-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@76d22d27 name:ZooKeeperConnection Watcher:127.0.0.1:53851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1779295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1779295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1779295 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1779298 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1779301 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1779302 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1779304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1779304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1779307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1779307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1779309 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1779309 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1779312 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1779312 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1779314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1779314 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1779315 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1779316 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1779317 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1779317 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1779319 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1779319 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1779323 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1779324 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1779325 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1779330 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1779331 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1779331 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1779333 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1779336 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1779336 INFO (zkCallback-2273-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a1807b1 name:ZooKeeperConnection Watcher:127.0.0.1:53851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1779337 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1779337 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1779338 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1779338 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1779339 INFO (zkCallback-2274-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1aa6522a name:ZooKeeperConnection Watcher:127.0.0.1:53851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1779339 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1779340 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1779340 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1779342 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1779342 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1779343 INFO (zkCallback-2275-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7e246fb9 name:ZooKeeperConnection Watcher:127.0.0.1:53851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1779344 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1779344 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1779344 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 1779345 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 1779349 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 1779350 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 1779352 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 1779352 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 1779353 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1779353 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1779355 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 1779355 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 1779356 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 1779357 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 1779359 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 1779360 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 1779361 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 1779361 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 1779363 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1779363 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 1779364 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 1779365 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 1779366 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 1779367 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 1779368 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 1779368 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 1779476 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1 [junit4] 2> 1779485 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1779487 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@63a28baf{/,null,AVAILABLE} [junit4] 2> 1779489 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.u.s.SslContextFactory x509=X509@6fb739c8(solrtest,h=[],w=[]) for SslContextFactory@54bbb088(file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/server/etc/test/solrtest.keystore,file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/server/etc/test/solrtest.keystore) [junit4] 2> 1779491 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.s.ServerConnector Started ServerConnector@48c539aa{SSL,[ssl, http/1.1]}{127.0.0.1:51846} [junit4] 2> 1779491 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.s.Server Started @1782551ms [junit4] 2> 1779491 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/tempDir-001/control/data, hostContext=/, hostPort=51846, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores} [junit4] 2> 1779491 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1779491 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001' [junit4] 2> 1779491 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1779492 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1779492 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1779492 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1779496 INFO (zkCallback-2276-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@530db8d5 name:ZooKeeperConnection Watcher:127.0.0.1:53851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1779496 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1779497 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1779498 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1779498 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/solr.xml [junit4] 2> 1779503 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores [junit4] 2> 1779503 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.CoreContainer New CoreContainer 62158486 [junit4] 2> 1779503 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001] [junit4] 2> 1779503 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/lib [junit4] 2> 1779504 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] 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> 1779506 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1779507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1779507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1779507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53851/solr [junit4] 2> 1779507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1779507 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1779508 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1779509 INFO (zkCallback-2279-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6959355d name:ZooKeeperConnection Watcher:127.0.0.1:53851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1779509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1779509 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1779511 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1779511 INFO (zkCallback-2280-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4bbf628e name:ZooKeeperConnection Watcher:127.0.0.1:53851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1779512 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1779513 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1779515 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1779520 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1779522 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1779525 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1779529 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1779534 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1779535 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1779536 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1779537 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1779538 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1779539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1779539 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95453506579726343-127.0.0.1:51846_-n_0000000000 [junit4] 2> 1779540 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51846_ [junit4] 2> 1779540 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1779542 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.Overseer Overseer (id=95453506579726343-127.0.0.1:51846_-n_0000000000) starting [junit4] 2> 1779545 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1779551 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1779552 INFO (OverseerCollectionConfigSetProcessor-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1779552 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1779552 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1779556 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1779558 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51846_ [junit4] 2> 1779559 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51846_ [junit4] 2> 1779559 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 1779560 INFO (zkCallback-2280-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ ] 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> 1779560 INFO (zkCallback-2280-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1779570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1779570 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1779579 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores [junit4] 2> 1779584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 1779584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1 [junit4] 2> 1779584 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1779594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1779594 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:51846_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1779595 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1779595 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1779598 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:51846";, [junit4] 2> "node_name":"127.0.0.1:51846_", [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> 1779598 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1779599 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:51846";, [junit4] 2> "node_name":"127.0.0.1:51846_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1779600 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1779601 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1779603 INFO (zkCallback-2280-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ ] 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> 1780599 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1780599 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1780600 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1780600 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1780600 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1780600 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1' [junit4] 2> 1780600 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1780600 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1780602 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1780607 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1780611 WARN (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 1780611 WARN (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <maxMergeDocs> is deprecated, configure it on the relevant <mergePolicyFactory> instead. [junit4] 2> 1780611 WARN (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergeFactor> is deprecated, configure it on the relevant <mergePolicyFactory> instead. [junit4] 2> 1780612 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1780623 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1780629 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1780760 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1780769 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1780771 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1780782 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1780784 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1780786 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1780788 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1780790 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1780790 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1780790 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 1780790 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1/data/] [junit4] 2> 1780790 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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@4315a3ba [junit4] 2> 1780798 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1/data [junit4] 2> 1780801 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1/data/index/ [junit4] 2> 1780801 WARN (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 1780802 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1/data/index [junit4] 2> 1780802 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=35, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=1.2822265625, noCFSRatio=1.0] [junit4] 2> 1780805 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@520d4d11 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6af215a4),segFN=segments_1,generation=1} [junit4] 2> 1780805 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1780809 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51846_ 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 /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1/data/ [junit4] 2> 1780810 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.DirectoryFactory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 1780810 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1780811 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1780811 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1780811 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1780811 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1780811 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780811 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780811 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780812 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780812 WARN (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780816 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1780818 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1780818 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1780820 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1780833 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file [junit4] 2> 1780834 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780841 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780842 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780842 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1780842 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1780843 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=31, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=2.0869140625, noCFSRatio=0.8107252188337569] [junit4] 2> 1780843 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@520d4d11 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6af215a4),segFN=segments_1,generation=1} [junit4] 2> 1780844 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1780844 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@25e3133b[collection1] main] [junit4] 2> 1780844 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780845 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780845 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780845 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1780845 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1780845 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780845 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780846 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780846 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1780846 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1780846 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780846 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780846 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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> 1780847 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ 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 1527256107024646144 [junit4] 2> 1780849 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1527256107024646144 [junit4] 2> 1780849 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1780850 INFO (searcherExecutor-5296-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@25e3133b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1780852 INFO (coreLoadExecutor-5295-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1780853 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:https://127.0.0.1:51846 collection:control_collection shard:shard1 [junit4] 2> 1780853 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ 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> 1780859 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ 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/95453506579726343-core_node1-n_0000000000 [junit4] 2> 1780859 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000 [junit4] 2> 1780863 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1780863 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] 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> 1780863 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ 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> 1780863 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:51846/collection1/ [junit4] 2> 1780863 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1780863 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:51846/collection1/ has no replicas [junit4] 2> 1780865 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1780866 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95453506579726343-core_node1-n_0000000000 [junit4] 2> 1780868 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:51846/collection1/ shard1 [junit4] 2> 1780869 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] 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":"https://127.0.0.1:51846";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 1780971 INFO (zkCallback-2280-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ ] 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> 1781020 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are https://127.0.0.1:51846/collection1/ and leader is https://127.0.0.1:51846/collection1/ [junit4] 2> 1781020 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:51846 [junit4] 2> 1781020 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1781020 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1781020 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ 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> 1781022 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] 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":"https://127.0.0.1:51846";, [junit4] 2> "node_name":"127.0.0.1:51846_", [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> 1781023 INFO (coreZkRegister-5289-thread-1-processing-n:127.0.0.1:51846_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51846_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 1781023 INFO (OverseerStateUpdate-95453506579726343-127.0.0.1:51846_-n_0000000000) [n:127.0.0.1:51846_ ] 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":"https://127.0.0.1:51846";, [junit4] 2> "node_name":"127.0.0.1:51846_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1781098 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1781099 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1781100 INFO (zkCallback-2282-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4d477355 name:ZooKeeperConnection Watcher:127.0.0.1:53851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1781101 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1781101 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1781101 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1781107 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1781108 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1781128 INFO (zkCallback-2282-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> 1781128 INFO (zkCallback-2280-thread-1-processing-n:127.0.0.1:51846_) [n:127.0.0.1:51846_ ] 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> 1781244 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/shard-1-001/cores/collection1 [junit4] 2> 1781245 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/shard-1-001 [junit4] 2> 1781247 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.s.Server jetty-9.3.6.v20151106 [junit4] 2> 1781248 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@118bedb5{/,null,AVAILABLE} [junit4] 2> 1781249 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.u.s.SslContextFactory x509=X509@2094f9e9(solrtest,h=[],w=[]) for SslContextFactory@37add135(file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/server/etc/test/solrtest.keystore,file:///x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/server/etc/test/solrtest.keystore) [junit4] 2> 1781251 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1f5322b5{SSL,[ssl, http/1.1]}{127.0.0.1:53384} [junit4] 2> 1781252 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.e.j.s.Server Started @1784311ms [junit4] 2> 1781252 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=53384, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/shard-1-001/cores} [junit4] 2> 1781252 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1781252 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/shard-1-001' [junit4] 2> 1781252 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1781252 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1781253 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1781256 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1781257 INFO (zkCallback-2283-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@133aba92 name:ZooKeeperConnection Watcher:127.0.0.1:53851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1781257 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1781257 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1781258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1781258 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/shard-1-001/solr.xml [junit4] 2> 1781263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/shard-1-001/cores [junit4] 2> 1781263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.CoreContainer New CoreContainer 1564778310 [junit4] 2> 1781263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/shard-1-001] [junit4] 2> 1781263 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.CollectionsAPIDistributedZkTest_AA5A12016F43EF92-001/shard-1-001/lib [junit4] 2> 1781263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] 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> 1781266 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1781267 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1781267 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1781267 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:53851/solr [junit4] 2> 1781267 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1781267 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1781272 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1781273 INFO (zkCallback-2286-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@68956a69 name:ZooKeeperConnection Watcher:127.0.0.1:53851 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1781273 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1781273 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1781278 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:53384_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1781291 INFO (zkCallback-2287-thread-1-processing-n:127.0.0.1:53384_) [n:127.0.0.1:53384_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@575f7c3a name:ZooKeeperConnection Watcher:127.0.0.1:53851/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1781291 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:53384_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1781300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:53384_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1781300 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:53384_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95453506579726347-127.0.0.1:53384_-n_0000000001 [junit4] 2> 1781301 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:53384_ ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95453506579726343-127.0.0.1:51846_-n_0000000000 to know if I could be the leader [junit4] 2> 1781301 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:53384_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1781303 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:53384_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 1781304 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:53384_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1781306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AA5A12016F43EF92]) [n:127.0.0.1:53384_ ] o.a.s.c.c.ZkStateReader Updated li [...truncated too long message...] c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [3]) [junit4] 2> 2270609 INFO (zkCallback-2705-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 2270610 INFO (zkCallback-2706-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2) [junit4] 2> 2272110 WARN (zkCallback-2704-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 2272111 INFO (zkCallback-2706-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: [2]) [junit4] 2> 2272111 INFO (zkCallback-2705-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: [2]) [junit4] 2> 2272113 INFO (zkCallback-2706-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2272113 INFO (zkCallback-2705-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1) [junit4] 2> 2272117 INFO (zkCallback-2706-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> 2273618 WARN (zkCallback-2706-thread-2) [ ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 2273619 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35161 35161 [junit4] 2> 2273722 INFO (Thread-8015) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:35161 35161 [junit4] 2> 2273724 WARN (Thread-8015) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 76 /solr/aliases.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 76 /solr/clusterstate.json [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard12/election/95453536699940871-node3_core3-n_0000000000 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard2/election/95453536699940934-node66_core66-n_0000000003 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard7/election/95453536699940919-node51_core51-n_0000000001 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard3/election/95453536699940924-node56_core56-n_0000000003 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard15/election/95453536699940922-node54_core54-n_0000000003 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard1/election/95453536699940932-node64_core64-n_0000000003 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard14/election/95453536699940929-node61_core61-n_0000000003 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard4/election/95453536699940931-node63_core63-n_0000000003 [junit4] 2> 2 /solr/collections/collection1/leader_elect/shard13/election/95453536699940935-node67_core67-n_0000000003 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 76 /solr/live_nodes [junit4] 2> 76 /solr/collections [junit4] 2> [junit4] 2> 2273725 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[AA5A12016F43EF92]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testShardAssignmentBigger [junit4] 2> 2273725 INFO (TEST-OverseerTest.testShardAssignmentBigger-seed#[AA5A12016F43EF92]) [ ] o.a.s.c.Overseer Overseer (id=95453536699940867-127.0.0.1:35161_solr-n_0000000000) closing [junit4] 2> 2273725 INFO (OverseerStateUpdate-95453536699940867-127.0.0.1:35161_solr-n_0000000000) [ ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:35161_solr [junit4] 2> 2273728 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:60) [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:308) [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater.access$300(Overseer.java:89) [junit4] 2> at org.apache.solr.cloud.Overseer$ClusterStateUpdater$2.run(Overseer.java:267) [junit4] 2> 2276728 INFO (SUITE-OverseerTest-seed#[AA5A12016F43EF92]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.OverseerTest_AA5A12016F43EF92-001 [junit4] 2> NOTE: test params are: codec=Lucene60, sim=ClassicSimilarity, locale=es-EC, timezone=Pacific/Johnston [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=77583984,total=480772096 [junit4] 2> NOTE: All tests run in this JVM: [SolrCoreCheckLockOnStartupTest, TestInitQParser, SecurityConfHandlerTest, DirectUpdateHandlerTest, TestTolerantSearch, LeaderInitiatedRecoveryOnShardRestartTest, TestCopyFieldCollectionResource, TestRawResponseWriter, TestCloudManagedSchemaConcurrent, HdfsDirectoryTest, OutOfBoxZkACLAndCredentialsProvidersTest, SampleTest, TestCollationField, TestExtendedDismaxParser, CoreAdminRequestStatusTest, ExitableDirectoryReaderTest, TestJettySolrRunner, TestRandomCollapseQParserPlugin, TestCloudPivotFacet, HLLUtilTest, TermVectorComponentDistributedTest, CollectionStateFormat2Test, TestConfigSets, DistributedQueryComponentCustomSortTest, TestRecoveryHdfs, DistributedFacetPivotSmallAdvancedTest, DirectSolrConnectionTest, SuggestComponentTest, DeleteReplicaTest, TestRuleBasedAuthorizationPlugin, TestConfigOverlay, SearchHandlerTest, TestIndexSearcher, RestartWhileUpdatingTest, TestMacros, TestDistribDocBasedVersion, TestPivotHelperCode, HighlighterMaxOffsetTest, CloneFieldUpdateProcessorFactoryTest, TestMiniSolrCloudClusterBase, TestRTimerTree, TestSearchPerf, StatelessScriptUpdateProcessorFactoryTest, CircularListTest, SimpleFacetsTest, TestRequestForwarding, FullSolrCloudDistribCmdsTest, TestFileDictionaryLookup, UnloadDistributedZkTest, UpdateParamsTest, DebugComponentTest, ExplicitHLLTest, TestLeaderElectionZkExpiry, StatsComponentTest, TestDynamicFieldResource, TestPerFieldSimilarityClassic, VersionInfoTest, TestClassicSimilarityFactory, TestPHPSerializedResponseWriter, CursorMarkTest, RulesTest, ScriptEngineTest, FieldAnalysisRequestHandlerTest, CdcrRequestHandlerTest, CustomCollectionTest, BigEndianAscendingWordDeserializerTest, PKIAuthenticationIntegrationTest, HdfsNNFailoverTest, TestSolrDynamicMBean, RegexBoostProcessorTest, TestFuzzyAnalyzedSuggestions, ConjunctionSolrSpellCheckerTest, TestExactStatsCache, SuggesterTest, TestRandomRequestDistribution, TestZkChroot, TestCSVResponseWriter, ConcurrentDeleteAndCreateCollectionTest, TestSimpleQParserPlugin, ZkStateWriterTest, TestOrdValues, TestReloadAndDeleteDocs, TestRecovery, DirectUpdateHandlerOptimizeTest, TestSolrJ, TestManagedSchema, TestJsonRequest, TestDefaultStatsCache, TestCoreDiscovery, TestSort, TestManagedSynonymFilterFactory, HdfsRestartWhileUpdatingTest, BlockCacheTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, OverseerTest] [junit4] Completed [234/580 (2!)] on J2 in 132.83s, 13 tests, 1 error, 1 skipped <<< FAILURES! [...truncated 1118 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1014: There were test failures: 580 suites (8 ignored), 2346 tests, 2 errors, 33 ignored (17 assumptions) [seed: AA5A12016F43EF92] Total time: 251 minutes 44 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
