Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/790/
3 tests failed. REGRESSION: org.apache.solr.cloud.DistribJoinFromCollectionTest.test Error Message: Expected: not "1.0" got: "1.0" Stack Trace: java.lang.AssertionError: Expected: not "1.0" got: "1.0" at __randomizedtesting.SeedInfo.seed([AAFB6F0BC146A2FD:22AF50D16FBACF05]:0) at org.junit.Assert.assertThat(Assert.java:780) at org.junit.Assert.assertThat(Assert.java:738) at org.apache.solr.cloud.DistribJoinFromCollectionTest.assertScore(DistribJoinFromCollectionTest.java:170) at org.apache.solr.cloud.DistribJoinFromCollectionTest.testJoins(DistribJoinFromCollectionTest.java:132) at org.apache.solr.cloud.DistribJoinFromCollectionTest.test(DistribJoinFromCollectionTest.java:100) 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:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:963) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:938) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=1649, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=1649, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:36605/_io/tv: collection already exists: awholynewstresscollection_collection5_3 at __randomizedtesting.SeedInfo.seed([AAFB6F0BC146A2FD]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) 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:1099) 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:1220) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1574) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:888) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=20492, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=20492, name=collection5, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:45331/_dnu/ah: Could not find collection : awholynewstresscollection_collection5_2 at __randomizedtesting.SeedInfo.seed([AAFB6F0BC146A2FD]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) 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:1099) 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:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:895) Build Log: [...truncated 9986 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/init-core-data-001 [junit4] 2> 125508 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[AAFB6F0BC146A2FD]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 125508 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[AAFB6F0BC146A2FD]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_io/tv [junit4] 2> 125518 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 125531 INFO (Thread-584) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 125531 INFO (Thread-584) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 125631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.ZkTestServer start zk server on port:38691 [junit4] 2> 125631 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 125648 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 125659 INFO (zkCallback-182-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62d8f189 name:ZooKeeperConnection Watcher:127.0.0.1:38691 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 125659 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 125660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 125660 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 125669 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 125696 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 125708 INFO (zkCallback-183-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3a28f54a name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 125708 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 125709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 125709 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 125711 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 125712 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 125713 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 125714 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125714 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 125716 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125717 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 125718 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125718 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 125722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125722 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 125724 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125724 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 125725 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125725 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 125726 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125727 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 125728 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125728 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 125729 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125730 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 125731 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125731 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 125732 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 125733 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 126183 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1 [junit4] 2> 126185 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 126203 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4d88cf05{/_io/tv,null,AVAILABLE} [junit4] 2> 126205 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2ff0102f{HTTP/1.1}{127.0.0.1:33752} [junit4] 2> 126206 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.e.j.s.Server Started @129386ms [junit4] 2> 126206 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/tempDir-001/control/data, hostContext=/_io/tv, hostPort=33752, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores} [junit4] 2> 126206 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 126206 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/' [junit4] 2> 126224 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 126233 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 126234 INFO (zkCallback-184-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@64a549f8 name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 126234 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 126235 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 126236 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 126236 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/solr.xml [junit4] 2> 126241 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores [junit4] 2> 126242 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.CoreContainer New CoreContainer 1399687175 [junit4] 2> 126242 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/] [junit4] 2> 126242 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/lib [junit4] 2> 126242 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/lib). [junit4] 2> 126260 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 126262 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 126262 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 126262 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 126262 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38691/solr [junit4] 2> 126263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 126263 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 126264 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 126278 INFO (zkCallback-186-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@34acd67a name:ZooKeeperConnection Watcher:127.0.0.1:38691 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 126279 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 126279 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 126306 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 126307 INFO (zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@692be441 name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 126307 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 126309 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 126311 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 126315 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 126318 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 126320 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 126323 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 126324 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 126326 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 126327 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 126328 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33752__io%2Ftv [junit4] 2> 126329 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:33752__io%2Ftv [junit4] 2> 126330 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 126332 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 126333 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 126333 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000 [junit4] 2> 126334 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33752__io%2Ftv [junit4] 2> 126334 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 126337 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer Overseer (id=94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) starting [junit4] 2> 126339 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 126347 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 126348 INFO (OverseerCollectionConfigSetProcessor-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 126349 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 126349 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 126355 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 126383 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 126384 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 126384 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores [junit4] 2> 126385 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 126385 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/ [junit4] 2> 126385 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 126391 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 126391 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 126392 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 126392 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 126394 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 126395 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33752/_io/tv", [junit4] 2> "node_name":"127.0.0.1:33752__io%2Ftv", [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> 126397 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33752/_io/tv", [junit4] 2> "node_name":"127.0.0.1:33752__io%2Ftv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 126397 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 126399 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 126401 INFO (zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv ] 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> 126401 INFO (zkCallback-187-thread-2-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv ] 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> 127395 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 127395 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 127395 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 127395 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 127396 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 127396 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/' [junit4] 2> 127420 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 127431 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 127435 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 127442 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 127443 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 127465 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 127556 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 127565 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 127566 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 127595 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 127597 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 127599 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 127600 WARN (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 127601 WARN (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 127601 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 127602 WARN (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 127602 WARN (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 127604 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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/J1 [junit4] 2> 127604 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 127604 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.MockDirectoryFactory [junit4] 2> 127604 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 127604 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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@6b6fa57a [junit4] 2> 127605 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data [junit4] 2> 127605 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/index/ [junit4] 2> 127605 WARN (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index... [junit4] 2> 127606 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/index [junit4] 2> 127606 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=9, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=92.2255859375, floorSegmentMB=2.0126953125, forceMergeDeletesPctAllowed=13.142628610524959, segmentsPerTier=26.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 127611 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@289889a9),segFN=segments_1,generation=1} [junit4] 2> 127612 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 127613 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/ [junit4] 2> 127614 WARN (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:33752__io%2Ftv 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/control-001/cores/collection1/data/ does not point to a valid data directory; skipping clean-up of old index directories. [junit4] 2> 127622 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 127622 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 127623 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 127623 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 127623 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 127623 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127624 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127624 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127624 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127642 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 127643 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 127644 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 127645 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 127649 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 127650 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127650 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127651 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127651 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 127652 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 127652 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=23, maxMergeAtOnceExplicit=24, maxMergedSegmentMB=70.0029296875, floorSegmentMB=0.3349609375, forceMergeDeletesPctAllowed=10.432124189999241, segmentsPerTier=8.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 127654 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=RawDirectoryWrapper(SimpleFSDirectory@/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/index-SimpleFSDirectory-001 lockFactory=org.apache.lucene.store.NativeFSLockFactory@289889a9),segFN=segments_1,generation=1} [junit4] 2> 127654 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 127654 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@52ebb3fc[collection1] main [junit4] 2> 127654 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127655 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127655 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127656 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 127656 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 127656 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127658 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127658 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127658 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 127658 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 127659 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127659 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127659 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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> 127659 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv 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 1511846897722064896 [junit4] 2> 127661 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1511846897722064896 [junit4] 2> 127661 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 127661 INFO (searcherExecutor-423-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@52ebb3fc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 127665 INFO (coreLoadExecutor-422-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 127666 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController Register replica - core:collection1 address:http://127.0.0.1:33752/_io/tv collection:control_collection shard:shard1 [junit4] 2> 127667 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv 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> 127681 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv 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/94490430969217028-core_node1-n_0000000000 [junit4] 2> 127682 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 127685 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 127685 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv 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> 127686 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33752/_io/tv/collection1/ [junit4] 2> 127686 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 127686 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:33752/_io/tv/collection1/ has no replicas [junit4] 2> 127686 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33752/_io/tv/collection1/ shard1 [junit4] 2> 127689 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] 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> 127693 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 127706 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 127718 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:33752/_io/tv", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 127820 INFO (zkCallback-187-thread-2-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv ] 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> 127821 INFO (zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv ] 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> 127859 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:33752/_io/tv/collection1/ and leader is http://127.0.0.1:33752/_io/tv/collection1/ [junit4] 2> 127859 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:33752/_io/tv [junit4] 2> 127859 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 127860 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 127860 INFO (coreZkRegister-416-thread-1-processing-n:127.0.0.1:33752__io%2Ftv x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:33752__io%2Ftv 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> 127861 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33752/_io/tv", [junit4] 2> "node_name":"127.0.0.1:33752__io%2Ftv", [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> 127862 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:33752/_io/tv", [junit4] 2> "node_name":"127.0.0.1:33752__io%2Ftv", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 127894 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 127912 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 127925 INFO (zkCallback-189-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4a3a7150 name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 127926 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 127926 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 127926 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 127931 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 127931 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 127931 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 127941 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 127943 INFO (zkCallback-190-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1626e664 name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 127943 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 127943 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 127945 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 2 [junit4] 2> 127946 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 127946 INFO (zkCallback-189-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> 127946 INFO (zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv ] 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> 127947 INFO (zkCallback-187-thread-2-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv ] 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> 128053 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 128801 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores/collection1 [junit4] 2> 128803 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001 [junit4] 2> 128807 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 128829 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1cc52219{/_io/tv,null,AVAILABLE} [junit4] 2> 128830 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.e.j.s.ServerConnector Started ServerConnector@54575593{HTTP/1.1}{127.0.0.1:56119} [junit4] 2> 128830 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.e.j.s.Server Started @132011ms [junit4] 2> 128830 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/_io/tv, hostPort=56119, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores} [junit4] 2> 128831 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 128832 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/' [junit4] 2> 128879 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 128893 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 128902 INFO (zkCallback-191-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5325ebc1 name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 128902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 128902 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 128903 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 128904 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/solr.xml [junit4] 2> 128909 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores [junit4] 2> 128909 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.CoreContainer New CoreContainer 15035131 [junit4] 2> 128909 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/] [junit4] 2> 128909 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/lib [junit4] 2> 128909 WARN (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/lib). [junit4] 2> 128928 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] 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> 128930 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 128930 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 128930 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 128930 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38691/solr [junit4] 2> 128930 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 128931 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 128931 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 128934 INFO (zkCallback-193-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@41f8ea37 name:ZooKeeperConnection Watcher:127.0.0.1:38691 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 128935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 128935 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 128939 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 128943 INFO (zkCallback-194-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3adacb99 name:ZooKeeperConnection Watcher:127.0.0.1:38691/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 128943 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 128948 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 129953 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56119__io%2Ftv [junit4] 2> 129953 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:56119__io%2Ftv [junit4] 2> 129954 INFO (zkCallback-189-thread-1) [ ] o.a.s.c.c.ZkStateReader A live node change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1) [junit4] 2> 129955 INFO (zkCallback-194-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv ] 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> 129955 INFO (zkCallback-187-thread-1-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv ] 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> 129955 INFO (zkCallback-187-thread-2-processing-n:127.0.0.1:33752__io%2Ftv) [n:127.0.0.1:33752__io%2Ftv ] 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> 129958 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 129959 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94490430969217033-127.0.0.1:56119__io%2Ftv-n_0000000001 [junit4] 2> 129960 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000 to know if I could be the leader [junit4] 2> 129979 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 129979 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 129980 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores [junit4] 2> 129981 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores/collection1, collection=collection1, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 129981 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest_AAFB6F0BC146A2FD-001/shard-1-001/cores/collection1/ [junit4] 2> 129981 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 129984 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 129991 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J1 [junit4] 2> 129991 INFO (TEST-CollectionsAPIDistributedZkTest.test-seed#[AAFB6F0BC146A2FD]) [n:127.0.0.1:56119__io%2Ftv ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 129991 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 129994 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56119/_io/tv", [junit4] 2> "node_name":"127.0.0.1:56119__io%2Ftv", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 129994 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56119/_io/tv", [junit4] 2> "node_name":"127.0.0.1:56119__io%2Ftv", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 129994 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ReplicaMutator Collection already exists with numShards=2 [junit4] 2> 129995 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 129997 INFO (OverseerStateUpdate-94490430969217028-127.0.0.1:33752__io%2Ftv-n_0000000000) [n:127.0.0.1:33752__io%2Ftv ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 130001 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController Registering watch for collection collection1 [junit4] 2> 130001 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader addZkWatch collection1 [junit4] 2> 130002 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Add data for collection1 ver 1 [junit4] 2> 130002 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 130002 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 130002 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController Check for collection zkNode:collection1 [junit4] 2> 130003 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 130003 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0.0.1:56119__io%2Ftv c:collection1 x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/collection1 [junit4] 2> 130004 INFO (coreLoadExecutor-433-thread-1-processing-n:127.0.0.1:56119__io%2Ftv) [n:127.0 [...truncated too long message...] nit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:497) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:799) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:198) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3291) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:6223) [junit4] 2> ... 53 more [junit4] 2> 4896403 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[AAFB6F0BC146A2FD]-worker) [n:127.0.0.1:47214__dnu%2Fah c:addReplicaColl s:shard2 r:core_node1 x:addReplicaColl_shard2_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> Sep 09, 2015 11:58:46 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 2 leaked thread(s). [junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=sv, timezone=Asia/Dubai [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_45 (64-bit)/cpus=4,threads=3,free=46723448,total=533200896 [junit4] 2> NOTE: All tests run in this JVM: [MBeansHandlerTest, HLLSerializationTest, ClusterStateUpdateTest, BitVectorTest, TestFreeTextSuggestions, MinimalSchemaTest, SpellCheckCollatorTest, DistributedTermsComponentTest, TestMissingGroups, TestAuthenticationFramework, NotRequiredUniqueKeyTest, AnalyticsQueryTest, CollectionsAPIDistributedZkTest, TestOverriddenPrefixQueryForCustomFieldType, TestRuleBasedAuthorizationPlugin, TestStressVersions, LoggingHandlerTest, TestFieldTypeResource, TermVectorComponentTest, TestJsonRequest, MergeStrategyTest, BasicFunctionalityTest, CSVRequestHandlerTest, DistributedExpandComponentTest, TestSolrQueryParserResource, ZkCLITest, TestBlendedInfixSuggestions, TestObjectReleaseTracker, SuggestComponentTest, TestCloudPivotFacet, ShardRoutingTest, TestReloadDeadlock, CachingDirectoryFactoryTest, FastVectorHighlighterTest, DistributedFacetPivotLongTailTest, ChaosMonkeySafeLeaderTest, DocumentBuilderTest, SimplePostToolTest, HdfsRecoverLeaseTest, ShardRoutingCustomTest, AsyncCallRequestStatusResponseTest, ChaosMonkeyNothingIsSafeTest, TestQueryTypes, TestStressUserVersions, TriLevelCompositeIdRoutingTest, TestDynamicLoading, CollectionsAPIAsyncDistributedZkTest, TestLeaderElectionZkExpiry, AddSchemaFieldsUpdateProcessorFactoryTest, SpellPossibilityIteratorTest, TestUtils, TestLFUCache, DOMUtilTest, TermsComponentTest, SolrCmdDistributorTest, TestShortCircuitedRequests, BJQParserTest, CoreAdminRequestStatusTest, DocExpirationUpdateProcessorFactoryTest, ZkSolrClientTest, ConvertedLegacyTest, TestConfigSets, TestJettySolrRunner, SoftAutoCommitTest, TestDFRSimilarityFactory, TestUpdate, TestQuerySenderNoQuery, MultiTermTest, TestManagedSchema, ShardSplitTest, TestQuerySenderListener, TestSchemaManager, SliceStateTest, HLLUtilTest, TestRTGBase, FacetPivotSmallTest, UniqFieldsUpdateProcessorFactoryTest, TestCustomDocTransformer, DistanceFunctionTest, PreAnalyzedUpdateProcessorTest, DeleteLastCustomShardedReplicaTest, TestClusterStateMutator, HdfsLockFactoryTest, SpatialFilterTest, TestWordDelimiterFilterFactory, TestBM25SimilarityFactory, DistributedFacetPivotSmallTest, TestFileDictionaryLookup, TestCodecSupport, IndexBasedSpellCheckerTest, TestRandomFaceting, TestZkChroot, AutoCommitTest, TestDistributedGrouping, TestFaceting, TestHashPartitioner, DistributedSpellCheckComponentTest, ZkControllerTest, TestRealTimeGet, TestRangeQuery, TestCoreContainer, TestSolr4Spatial, StatsComponentTest, TestLazyCores, TestBadConfig, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, CoreAdminHandlerTest, TestStressLucene, TestCSVLoader, SchemaVersionSpecificBehaviorTest, QueryEqualityTest, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, DebugComponentTest, LukeRequestHandlerTest, DirectSolrSpellCheckerTest, RequestHandlersTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, IndexSchemaRuntimeFieldTest, TestCollationField, JSONWriterTest, BinaryUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, SearchHandlerTest, ResponseLogComponentTest, SolrIndexConfigTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, NumericFieldsTest, TestConfig, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, TestPostingsSolrHighlighter, DirectSolrConnectionTest, TestCharFilters, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestLMJelinekMercerSimilarityFactory, TestJmxMonitoredMap, ResourceLoaderTest, TestFastOutputStream, URLClassifyProcessorTest, TestFastLRUCache, DateMathParserTest, PreAnalyzedFieldTest, TestSuggestSpellingConverter, SpellingQueryConverterTest, TestLRUCache, ZkNodePropsTest, SystemInfoHandlerTest, UUIDFieldTest, CircularListTest, DistributedMLTComponentTest, CursorPagingTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestHighlightDedupGrouping, TestTolerantSearch, TestEmbeddedSolrServerConstructors, AsyncMigrateRouteKeyTest, CdcrRequestHandlerTest, CloudExitableDirectoryReaderTest, CollectionStateFormat2Test, ConcurrentDeleteAndCreateCollectionTest, DeleteInactiveReplicaTest, DeleteShardTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerStatusTest, RemoteQueryErrorTest, ReplicationFactorTest, SaslZkACLProviderTest, SharedFSAutoReplicaFailoverUtilsTest, SolrCloudExampleTest, SolrXmlInZkTest, TestCollectionAPI, TestConfigSetsAPI, TestConfigSetsAPIExclusivity, TestDistribDocBasedVersion, TestDownShardTolerantSearch, TestMiniSolrCloudClusterBase, TestRandomRequestDistribution, TestReplicaProperties, VMParamsZkACLAndCredentialsProvidersTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [544/544] on J1 in 1421.84s, 1 test, 1 error <<< FAILURES! [...truncated 13 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:782: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:719: 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:514: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1432: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:987: There were test failures: 544 suites, 2142 tests, 2 errors, 1 failure, 29 ignored (17 assumptions) Total time: 191 minutes 59 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 Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org