Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/2/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([E66205D332A8EEF8:6E363A099C548300]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNull(Assert.java:551) at org.junit.Assert.assertNull(Assert.java:562) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testNoConfigSetExist(CollectionsAPIDistributedZkTest.java:518) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:165) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) 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) Build Log: [...truncated 10617 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/init-core-data-001 [junit4] 2> 926549 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 926549 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_/rx [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 926599 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 926615 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 926618 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 926634 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_47244_hdfs____9jzmgp/webapp [junit4] 2> 926782 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 927017 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:47244 [junit4] 2> 927158 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 927160 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 927174 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_57642_datanode____btwzzt/webapp [junit4] 2> 927298 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 927557 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:57642 [junit4] 2> 927602 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 927604 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 927622 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.6.0-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_46012_datanode____fg0q9i/webapp [junit4] 2> 927667 INFO (IPC Server handler 3 on 40518) [ ] BlockStateChange BLOCK* processReport: from storage DS-623ff233-b980-481f-b99e-39a3286fa9f9 node DatanodeRegistration(127.0.0.1, datanodeUuid=3fc8e770-74c6-4624-a4ca-c18562b58fd8, infoPort=57642, ipcPort=51352, storageInfo=lv=-56;cid=testClusterID;nsid=151573473;c=0), blocks: 0, hasStaleStorages: true, processing time: 1 msecs [junit4] 2> 927667 INFO (IPC Server handler 3 on 40518) [ ] BlockStateChange BLOCK* processReport: from storage DS-dcc2a8e2-ac4b-4c14-a772-bf1bc67c9e0a node DatanodeRegistration(127.0.0.1, datanodeUuid=3fc8e770-74c6-4624-a4ca-c18562b58fd8, infoPort=57642, ipcPort=51352, storageInfo=lv=-56;cid=testClusterID;nsid=151573473;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 927757 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 927973 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:46012 [junit4] 2> 928053 INFO (IPC Server handler 8 on 40518) [ ] BlockStateChange BLOCK* processReport: from storage DS-63f26b3f-1172-436e-9b66-84db40aa6f22 node DatanodeRegistration(127.0.0.1, datanodeUuid=b2c1bc6b-aa95-46fb-ad1a-330a6d1947b1, infoPort=46012, ipcPort=42744, storageInfo=lv=-56;cid=testClusterID;nsid=151573473;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 928054 INFO (IPC Server handler 8 on 40518) [ ] BlockStateChange BLOCK* processReport: from storage DS-0ec8f07f-67d7-410c-a069-2ef6606c6cfb node DatanodeRegistration(127.0.0.1, datanodeUuid=b2c1bc6b-aa95-46fb-ad1a-330a6d1947b1, infoPort=46012, ipcPort=42744, storageInfo=lv=-56;cid=testClusterID;nsid=151573473;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 928136 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 928136 INFO (Thread-7346) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 928136 INFO (Thread-7346) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 928236 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.ZkTestServer start zk server on port:40111 [junit4] 2> 928237 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 928237 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 928240 INFO (zkCallback-600-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1fb056f3 name:ZooKeeperConnection Watcher:127.0.0.1:40111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 928240 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 928241 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 928241 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 928243 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 928244 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 928245 INFO (zkCallback-601-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@76f66e02 name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 928245 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 928245 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 928245 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 928247 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 928249 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 928250 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 928252 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 928252 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 928255 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 928255 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 928257 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 928257 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 928261 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 928261 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 928265 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 928265 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 928267 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 928267 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 928269 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 928269 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 928274 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 928274 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 928276 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 928276 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 928278 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 928278 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 928280 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 928280 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 928282 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 928282 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 928284 INFO (zkCallback-602-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1014e07b name:ZooKeeperConnection Watcher:127.0.0.1:40111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 928284 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 928284 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 928284 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 928286 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 928288 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 928288 INFO (zkCallback-603-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b3f64a9 name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 928289 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 928289 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 928289 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 928290 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 928292 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 928292 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 928294 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 928294 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 928296 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 928297 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 928298 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 928299 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 928301 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 928301 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 928304 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 928304 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 928306 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 928306 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 928308 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 928308 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 928309 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 928310 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 928311 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 928312 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 928314 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 928314 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 928405 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1 [junit4] 2> 928407 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 928409 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7baf1b69{/_/rx,null,AVAILABLE} [junit4] 2> 928409 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.e.j.s.ServerConnector Started ServerConnector@26197cfc{HTTP/1.1}{127.0.0.1:44944} [junit4] 2> 928409 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.e.j.s.Server Started @931662ms [junit4] 2> 928409 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/_/rx, solr.data.dir=hdfs://localhost:40518/hdfs__localhost_40518__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001_tempDir-002_control_data, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores, hostPort=44944} [junit4] 2> 928410 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 928410 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/' [junit4] 2> 928426 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 928427 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 928429 INFO (zkCallback-604-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6a0d45e4 name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 928429 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 928430 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 928431 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 928431 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/solr.xml [junit4] 2> 928446 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores [junit4] 2> 928446 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.CoreContainer New CoreContainer 743402390 [junit4] 2> 928446 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/] [junit4] 2> 928446 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/lib [junit4] 2> 928446 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/lib). [junit4] 2> 928454 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] 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> 928463 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 928463 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 928463 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 928463 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40111/solr [junit4] 2> 928464 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 928464 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 928464 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 928466 INFO (zkCallback-606-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2139e0e4 name:ZooKeeperConnection Watcher:127.0.0.1:40111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 928467 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 928468 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 928469 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 928471 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@62f7cc64 name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 928471 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 928472 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 928474 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 928477 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 928479 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 928482 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 928485 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 928487 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 928488 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 928489 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 928491 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44944__%2Frx [junit4] 2> 928491 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:44944__%2Frx [junit4] 2> 928493 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 928494 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 928496 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 928496 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000 [junit4] 2> 928497 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44944__%2Frx [junit4] 2> 928497 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 928499 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.Overseer Overseer (id=95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) starting [junit4] 2> 928503 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 928509 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 928510 INFO (OverseerCollectionProcessor-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 928510 INFO (OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 928510 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 928531 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 928532 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 928533 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores [junit4] 2> 928533 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.SolrCore Created CoreDescriptor: {coreNodeName=, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1, config=solrconfig.xml, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1/, dataDir=data/, loadOnStartup=true, schema=schema.xml, transient=false, collection=control_collection, shard=, configSetProperties=configsetprops.json, name=collection1} [junit4] 2> 928534 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1/ [junit4] 2> 928534 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 928535 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 928535 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2 [junit4] 2> 928535 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:44944__%2Frx ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 928535 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 928536 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 928536 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 928537 INFO (OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "shard":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:44944/_/rx", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:44944__%2Frx", [junit4] 2> "roles":null} current state version: 0 [junit4] 2> 928537 INFO (OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "shard":null, [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:44944/_/rx", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:44944__%2Frx", [junit4] 2> "roles":null} [junit4] 2> 928538 INFO (OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 928538 INFO (OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 928539 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] 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> 928541 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 929536 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 929537 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 929537 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 929537 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 929538 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 929538 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1/' [junit4] 2> 929553 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 929558 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 929570 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.2 [junit4] 2> 929618 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 929620 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 929627 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 929865 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 929873 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 929875 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 929884 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 929887 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 929890 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 929891 WARN (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 929891 WARN (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 929891 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 929892 WARN (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 929892 WARN (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 929893 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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-5.3/solr/build/solr-core/test/J2 [junit4] 2> 929893 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 929893 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 929894 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:40518/solr_hdfs_home [junit4] 2> 929894 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 929894 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 929894 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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@458b797d [junit4] 2> 929894 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 929909 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 929909 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 929910 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 929920 WARN (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 929920 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 929933 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 929933 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 929934 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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=38, maxMergeAtOnceExplicit=22, maxMergedSegmentMB=3.25, floorSegmentMB=1.544921875, forceMergeDeletesPctAllowed=25.903679207795463, segmentsPerTier=17.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6018182887718883 [junit4] 2> 929950 INFO (IPC Server handler 7 on 40518) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:55552 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-63f26b3f-1172-436e-9b66-84db40aa6f22:NORMAL:127.0.0.1:47967|RBW], ReplicaUnderConstruction[[DISK]DS-dcc2a8e2-ac4b-4c14-a772-bf1bc67c9e0a:NORMAL:127.0.0.1:55552|FINALIZED]]} size 0 [junit4] 2> 929952 INFO (IPC Server handler 8 on 40518) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:47967 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-dcc2a8e2-ac4b-4c14-a772-bf1bc67c9e0a:NORMAL:127.0.0.1:55552|FINALIZED], ReplicaUnderConstruction[[DISK]DS-0ec8f07f-67d7-410c-a069-2ef6606c6cfb:NORMAL:127.0.0.1:47967|FINALIZED]]} size 0 [junit4] 2> 929955 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@b6de4b64 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31d6fbbb; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 929955 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 929957 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:44944__%2Frx 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 hdfs://localhost:40518/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 929962 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 929962 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 929962 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 929962 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 929962 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 929963 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 929963 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 929963 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 929963 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 929966 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 929968 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 929969 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 929971 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 929980 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /update,/admin/logging,/admin/ping,/admin/plugins,/schema,/admin/segments,/admin/properties,/config,/admin/mbeans,/admin/file,/get,/update/json,/update/csv,/admin/system,/replication,/admin/luke,standard,/update/json/docs,/admin/threads [junit4] 2> 929982 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 929982 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog [junit4] 2> 929982 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing HdfsUpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 tlogDfsReplication=2 [junit4] 2> 929994 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 929994 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 929996 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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=27, maxMergeAtOnceExplicit=48, maxMergedSegmentMB=87.1708984375, floorSegmentMB=0.7177734375, forceMergeDeletesPctAllowed=25.426875340826996, segmentsPerTier=37.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 930002 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@b6de4b64 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31d6fbbb; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 930002 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 930002 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@7a7b444d[collection1] main [junit4] 2> 930002 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930003 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930004 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930004 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 930004 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 930004 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930005 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930005 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930005 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 930005 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 930006 INFO (searcherExecutor-2043-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@7a7b444d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 930008 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930008 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 256 version buckets from index [junit4] 2> 930008 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930008 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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 1522306876077768704 [junit4] 2> 930008 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 0 ms to seed version buckets with highest version 1522306876077768704 [junit4] 2> 930009 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 930009 INFO (coreLoadExecutor-2042-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 930010 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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:44944/_/rx collection:control_collection shard:shard1 [junit4] 2> 930011 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930014 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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/95144179632308230-core_node1-n_0000000000 [junit4] 2> 930015 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 930017 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 930018 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 930018 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930018 INFO (OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] 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> 930018 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:44944/_/rx/collection1/ [junit4] 2> 930018 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 930018 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:44944/_/rx/collection1/ has no replicas [junit4] 2> 930018 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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:44944/_/rx/collection1/ shard1 [junit4] 2> 930018 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 930023 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 930024 INFO (OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] 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:44944/_/rx", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 930037 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 930038 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 930039 INFO (zkCallback-609-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@251d4e5a name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 930039 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 930040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 930040 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 930043 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 930127 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] 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> 930127 INFO (zkCallback-609-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> 930128 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 930132 INFO (zkCallback-609-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 930144 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/cores/collection1 [junit4] 2> 930145 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001 [junit4] 2> 930146 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 930148 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5102f4be{/_/rx,null,AVAILABLE} [junit4] 2> 930149 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.e.j.s.ServerConnector Started ServerConnector@70b767b4{HTTP/1.1}{127.0.0.1:49570} [junit4] 2> 930149 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.e.j.s.Server Started @933402ms [junit4] 2> 930149 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:40518/hdfs__localhost_40518__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/_/rx, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/cores, hostPort=49570} [junit4] 2> 930150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@7b3cb2c6 [junit4] 2> 930150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/' [junit4] 2> 930167 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 930168 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 930169 INFO (zkCallback-610-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@becda9c name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 930169 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 930170 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 930171 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 930171 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/solr.xml [junit4] 2> 930174 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:44944/_/rx/collection1/ and leader is http://127.0.0.1:44944/_/rx/collection1/ [junit4] 2> 930174 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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:44944/_/rx [junit4] 2> 930174 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 930174 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 930174 INFO (coreZkRegister-2036-thread-1-processing-r:core_node1 x:collection1 s:shard1 c:control_collection n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx 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> 930175 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 930176 INFO (OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "collection":"control_collection", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "base_url":"http://127.0.0.1:44944/_/rx", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:44944__%2Frx", [junit4] 2> "roles":null} current state version: 2 [junit4] 2> 930177 INFO (OverseerStateUpdate-95144179632308230-127.0.0.1:44944__%2Frx-n_0000000000) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "collection":"control_collection", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "base_url":"http://127.0.0.1:44944/_/rx", [junit4] 2> "operation":"state", [junit4] 2> "node_name":"127.0.0.1:44944__%2Frx", [junit4] 2> "roles":null} [junit4] 2> 930187 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/cores [junit4] 2> 930188 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.CoreContainer New CoreContainer 964384298 [junit4] 2> 930188 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/] [junit4] 2> 930188 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/lib [junit4] 2> 930188 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] 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-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001/shard-1-001/lib). [junit4] 2> 930197 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] 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> 930198 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 930199 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 930199 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 930199 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40111/solr [junit4] 2> 930199 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 930199 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 930200 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 930201 INFO (zkCallback-612-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4ad52a35 name:ZooKeeperConnection Watcher:127.0.0.1:40111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 930202 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 930202 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 930204 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:49570__%2Frx ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 930205 INFO (zkCallback-613-thread-1-processing-n:127.0.0.1:49570__%2Frx) [n:127.0.0.1:49570__%2Frx ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@103ce784 name:ZooKeeperConnection Watcher:127.0.0.1:40111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 930206 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:49570__%2Frx ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 930210 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E66205D332A8EEF8]) [n:127.0.0.1:49570__%2Frx ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 930280 INFO (zkCallback-609-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> 930280 INFO (zkCallback-607-thread-1-processing-n:127.0.0.1:44944__%2Frx) [n:127.0.0.1:44944__%2Frx ] o.a.s.c.c.ZkStateReader A cluster state change: WatchedEvent state:SyncConnected type [...truncated too long message...] MetricsRegistry.java:387) [junit4] 2> at org.apache.hadoop.metrics2.lib.MetricsSourceBuilder$1.getMetrics(MetricsSourceBuilder.java:79) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMetrics(MetricsSourceAdapter.java:195) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.updateJmxCache(MetricsSourceAdapter.java:172) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.getMBeanInfo(MetricsSourceAdapter.java:151) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getClassName(DefaultMBeanServerInterceptor.java:1804) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.safeGetClassName(DefaultMBeanServerInterceptor.java:1595) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.checkMBeanPermission(DefaultMBeanServerInterceptor.java:1813) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.exclusiveUnregisterMBean(DefaultMBeanServerInterceptor.java:430) [junit4] 2> at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.unregisterMBean(DefaultMBeanServerInterceptor.java:415) [junit4] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:227) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:461) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:212) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:592) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:145) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:822) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1720) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1699) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:197) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.teardownClass(HdfsCollectionsAPIDistributedZkTest.java:50) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:606) [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> 959641 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E66205D332A8EEF8]-worker) [n:127.0.0.1:50949__%2Frx c:nodes_used_collection s:shard2 r:core_node1 x:nodes_used_collection_shard2_replica1] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E66205D332A8EEF8-001 [junit4] 2> Jan 03, 2016 1:36:39 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=Asserting(Lucene53): {}, docValues:{}, sim=DefaultSimilarity, locale=es_US, timezone=Australia/Sydney [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_80 (64-bit)/cpus=4,threads=2,free=149325592,total=406323200 [junit4] 2> NOTE: All tests run in this JVM: [DirectUpdateHandlerOptimizeTest, HdfsRecoverLeaseTest, DirectSolrSpellCheckerTest, RecoveryZkTest, TermVectorComponentDistributedTest, CleanupOldIndexTest, TestPivotHelperCode, TestPerFieldSimilarity, SampleTest, TestManagedSchemaDynamicFieldResource, TestConfigSetProperties, ExitableDirectoryReaderTest, DistribJoinFromCollectionTest, TestClassNameShortening, ResponseLogComponentTest, TestAuthenticationFramework, TestNonDefinedSimilarityFactory, TestSchemaManager, HdfsRecoveryZkTest, TestDocBasedVersionConstraints, DistributedExpandComponentTest, TestSearcherReuse, DirectUpdateHandlerTest, QueryResultKeyTest, CoreAdminRequestStatusTest, TestUtils, TestBulkSchemaAPI, TestTolerantSearch, TestDefaultSimilarityFactory, TestRemoveLastDynamicCopyField, PathHierarchyTokenizerFactoryTest, TestCollationFieldDocValues, StatsComponentTest, TestBlendedInfixSuggestions, TestSolrCLIRunExample, TestRTimer, TestFastOutputStream, TestStressRecovery, RegexBoostProcessorTest, SortByFunctionTest, TestFieldCollectionResource, SpellCheckCollatorTest, TestSolrJ, TestRecovery, LeaderElectionTest, TestExtendedDismaxParser, QueryParsingTest, TestSchemaSimilarityResource, SolrTestCaseJ4Test, TestCloudInspectUtil, TestBM25SimilarityFactory, TestGroupingSearch, CloudExitableDirectoryReaderTest, StressHdfsTest, TestTrieFacet, TestStressReorder, RecoveryAfterSoftCommitTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestTrie, TestBinaryResponseWriter, PrimUtilsTest, LoggingHandlerTest, SynonymTokenizerTest, TestSimpleTrackingShardHandler, DistributedQueryElevationComponentTest, TestZkChroot, XsltUpdateRequestHandlerTest, TestSurroundQueryParser, BadIndexSchemaTest, CollectionReloadTest, SolrIndexSplitterTest, CoreMergeIndexesAdminHandlerTest, HdfsCollectionsAPIDistributedZkTest] [junit4] Completed [142/533] on J2 in 42.95s, 1 test, 1 failure <<< FAILURES! [...truncated 1239 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:733: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:670: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1449: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/lucene/common-build.xml:1003: There were test failures: 533 suites, 2103 tests, 1 failure, 26 ignored (14 assumptions) Total time: 175 minutes 23 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org