Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-5.3/3/
3 tests failed. FAILED: junit.framework.TestSuite.org.apache.solr.cloud.CollectionsAPIDistributedZkTest Error Message: Java heap space Stack Trace: java.lang.OutOfMemoryError: Java heap space FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=71617, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=71617, name=collection4, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:48466/a_/p: Could not find collection : awholynewstresscollection_collection4_0 at __randomizedtesting.SeedInfo.seed([E1E4810DB75117FB]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:560) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:234) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:226) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:376) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:328) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1085) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:856) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:799) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:894) REGRESSION: org.apache.solr.core.TestDynamicLoading.testDynamicLoading Error Message: Could not get expected value 'X val changed' for path 'x' full output: { "responseHeader":{ "status":0, "QTime":0}, "params":{"wt":"json"}, "context":{ "httpMethod":"GET", "path":"/test1", "webapp":"/hrv/d"}, "class":"org.apache.solr.core.BlobStoreTestRequestHandler", "x":"X val"} Stack Trace: java.lang.AssertionError: Could not get expected value 'X val changed' for path 'x' full output: { "responseHeader":{ "status":0, "QTime":0}, "params":{"wt":"json"}, "context":{ "httpMethod":"GET", "path":"/test1", "webapp":"/hrv/d"}, "class":"org.apache.solr.core.BlobStoreTestRequestHandler", "x":"X val"} at __randomizedtesting.SeedInfo.seed([E1E4810DB75117FB:39A9AC5A408CB25B]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:411) at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:260) 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 11755 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/init-core-data-001 [junit4] 2> 4142999 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 4142999 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /a_/p [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 4143057 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 4143070 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4143074 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4143109 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-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_43140_hdfs____88nqrc/webapp [junit4] 2> 4143277 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4143854 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:43140 [junit4] 2> 4143979 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4143981 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4143997 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-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_33402_datanode____.fktsub/webapp [junit4] 2> 4144159 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4144819 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33402 [junit4] 2> 4145011 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4145013 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4145081 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-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_43988_datanode____zgls75/webapp [junit4] 2> 4145272 INFO (IPC Server handler 3 on 53601) [ ] BlockStateChange BLOCK* processReport: from storage DS-f311bc1c-8d2f-4217-b718-137faecb7d6e node DatanodeRegistration(127.0.0.1, datanodeUuid=269d46f5-8fe4-4886-91c3-20436df9ca99, infoPort=33402, ipcPort=57618, storageInfo=lv=-56;cid=testClusterID;nsid=826795487;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 4145273 INFO (IPC Server handler 3 on 53601) [ ] BlockStateChange BLOCK* processReport: from storage DS-2da7b6f3-68ce-4bb2-85ce-9b074ca22863 node DatanodeRegistration(127.0.0.1, datanodeUuid=269d46f5-8fe4-4886-91c3-20436df9ca99, infoPort=33402, ipcPort=57618, storageInfo=lv=-56;cid=testClusterID;nsid=826795487;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 4145353 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4146005 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[E1E4810DB75117FB]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:43988 [junit4] 2> 4146135 INFO (IPC Server handler 4 on 53601) [ ] BlockStateChange BLOCK* processReport: from storage DS-bbb19e74-c902-4097-b32b-2fc9643a4f59 node DatanodeRegistration(127.0.0.1, datanodeUuid=f8f5042a-7143-4827-b213-f2b8aae69b94, infoPort=43988, ipcPort=41009, storageInfo=lv=-56;cid=testClusterID;nsid=826795487;c=0), blocks: 0, hasStaleStorages: true, processing time: 1 msecs [junit4] 2> 4146136 INFO (IPC Server handler 4 on 53601) [ ] BlockStateChange BLOCK* processReport: from storage DS-f149f68e-cef4-43aa-962a-c1a441fe49be node DatanodeRegistration(127.0.0.1, datanodeUuid=f8f5042a-7143-4827-b213-f2b8aae69b94, infoPort=43988, ipcPort=41009, storageInfo=lv=-56;cid=testClusterID;nsid=826795487;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 4146225 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 4146233 INFO (Thread-60418) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4146233 INFO (Thread-60418) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 4146333 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.ZkTestServer start zk server on port:47415 [junit4] 2> 4146333 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4146335 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4146337 INFO (zkCallback-2147-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5c886fe2 name:ZooKeeperConnection Watcher:127.0.0.1:47415 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4146337 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4146338 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4146338 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 4146342 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4146343 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4146345 INFO (zkCallback-2148-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5e15da2a name:ZooKeeperConnection Watcher:127.0.0.1:47415/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4146346 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4146346 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4146346 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 4146348 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 4146350 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 4146351 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 4146353 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146353 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4146356 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146356 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 4146358 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146358 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4146360 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146360 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 4146361 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146362 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 4146364 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146364 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 4146365 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146366 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4146367 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146367 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4146369 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146369 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4146370 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146371 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4146372 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146372 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 4146374 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4146381 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4146382 INFO (zkCallback-2149-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@70bac525 name:ZooKeeperConnection Watcher:127.0.0.1:47415 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4146382 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4146382 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4146382 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 4146384 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4146393 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4146400 INFO (zkCallback-2150-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@161e0d88 name:ZooKeeperConnection Watcher:127.0.0.1:47415/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4146400 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4146401 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4146401 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146402 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 4146404 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146404 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 4146406 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146406 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 4146407 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146408 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4146410 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146410 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 4146411 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146411 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 4146413 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146413 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 4146414 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146415 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 4146416 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146417 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 4146418 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146418 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 4146419 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146420 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 4146422 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4146422 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 4146894 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/cores/collection1 [junit4] 2> 4146896 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 4146952 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@11cccc11{/a_/p,null,AVAILABLE} [junit4] 2> 4146953 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@50955a1a{HTTP/1.1}{127.0.0.1:48466} [junit4] 2> 4146953 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.e.j.s.Server Started @4150094ms [junit4] 2> 4146953 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/a_/p, hostPort=48466, solr.data.dir=hdfs://localhost:53601/hdfs__localhost_53601__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001_tempDir-002_control_data, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/cores} [junit4] 2> 4146953 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1fe91485 [junit4] 2> 4146954 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/' [junit4] 2> 4146971 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4146994 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4147009 INFO (zkCallback-2151-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@18c2b361 name:ZooKeeperConnection Watcher:127.0.0.1:47415/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4147009 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4147010 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4147011 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4147011 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/solr.xml [junit4] 2> 4147031 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/cores [junit4] 2> 4147032 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.CoreContainer New CoreContainer 1284028412 [junit4] 2> 4147032 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/] [junit4] 2> 4147032 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/lib [junit4] 2> 4147032 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/lib). [junit4] 2> 4147041 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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> 4147042 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4147043 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4147043 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4147043 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:47415/solr [junit4] 2> 4147044 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 4147044 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4147044 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4147046 INFO (zkCallback-2153-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6ed3c5f8 name:ZooKeeperConnection Watcher:127.0.0.1:47415 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4147046 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4147047 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4147048 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4147050 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@69c4cc30 name:ZooKeeperConnection Watcher:127.0.0.1:47415/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4147050 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4147051 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 4147054 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 4147055 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 4147057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 4147058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 4147060 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 4147061 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 4147062 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 4147063 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 4147064 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48466_a_%2Fp [junit4] 2> 4147065 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:48466_a_%2Fp [junit4] 2> 4147066 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 4147067 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 4147068 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4147069 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000 [junit4] 2> 4147069 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:48466_a_%2Fp [junit4] 2> 4147069 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 4147070 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.Overseer Overseer (id=94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) starting [junit4] 2> 4147072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 4147076 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4147081 INFO (OverseerStateUpdate-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 4147084 INFO (OverseerCollectionProcessor-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 4147089 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4147103 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 4147103 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 4147104 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/cores [junit4] 2> 4147116 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.SolrCore Created CoreDescriptor: {dataDir=data/, collection=control_collection, schema=schema.xml, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/cores/collection1, coreNodeName=, config=solrconfig.xml, shard=, loadOnStartup=true, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/cores/collection1/, configSetProperties=configsetprops.json, transient=false, name=collection1} [junit4] 2> 4147116 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/cores/collection1/ [junit4] 2> 4147116 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 4147125 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 4147126 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 4147127 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 4147131 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4147132 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1 [junit4] 2> 4147132 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 4147133 INFO (OverseerStateUpdate-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:48466_a_%2Fp", [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:48466/a_/p";, [junit4] 2> "numShards":"1", [junit4] 2> "operation":"state", [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "state":"down", [junit4] 2> "roles":null} current state version: 0 [junit4] 2> 4147133 INFO (OverseerStateUpdate-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "node_name":"127.0.0.1:48466_a_%2Fp", [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:48466/a_/p";, [junit4] 2> "numShards":"1", [junit4] 2> "operation":"state", [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "state":"down", [junit4] 2> "roles":null} [junit4] 2> 4147134 INFO (OverseerStateUpdate-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 4147134 INFO (OverseerStateUpdate-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 4147136 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] 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> 4147138 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 4148127 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 4148127 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 4148128 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 4148128 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 4148128 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4148128 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/cores/collection1/' [junit4] 2> 4148163 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 4148171 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 4148212 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 5.3.0 [junit4] 2> 4148259 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 4148263 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4148274 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 4148731 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4148739 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 4148741 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 4148750 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 4148754 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 4148757 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4148757 WARN (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 4148758 WARN (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 4148758 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4148758 WARN (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 4148759 WARN (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 4148760 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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/J1 [junit4] 2> 4148760 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4148760 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 4148760 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:53601/solr_hdfs_home [junit4] 2> 4148760 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 4148761 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 4148761 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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@32a9f661 [junit4] 2> 4148761 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:53601/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 4148787 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:53601/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 4148787 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:53601/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 4148789 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:53601/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 4148811 WARN (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:53601/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 4148811 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:53601/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 4148842 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:53601/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 4148842 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:53601/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 4148842 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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=30, maxMergeAtOnceExplicit=17, maxMergedSegmentMB=80.7861328125, floorSegmentMB=2.0009765625, forceMergeDeletesPctAllowed=9.832215830118637, segmentsPerTier=47.0, maxCFSSegmentSizeMB=1.353515625, noCFSRatio=1.0 [junit4] 2> 4148932 INFO (IPC Server handler 3 on 53601) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:53824 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-2da7b6f3-68ce-4bb2-85ce-9b074ca22863:NORMAL:127.0.0.1:56013|RBW], ReplicaUnderConstruction[[DISK]DS-f149f68e-cef4-43aa-962a-c1a441fe49be:NORMAL:127.0.0.1:53824|RBW]]} size 0 [junit4] 2> 4148935 INFO (IPC Server handler 2 on 53601) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:56013 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-f149f68e-cef4-43aa-962a-c1a441fe49be:NORMAL:127.0.0.1:53824|RBW], ReplicaUnderConstruction[[DISK]DS-f311bc1c-8d2f-4217-b718-137faecb7d6e:NORMAL:127.0.0.1:56013|FINALIZED]]} size 0 [junit4] 2> 4148938 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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@b8635ff3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70f67bcc; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 4148938 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4148943 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:48466_a_%2Fp 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:53601/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 4148947 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4148947 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 4148947 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4148948 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4148948 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4148948 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4148948 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4148949 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4148949 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4148952 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4148953 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4148955 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4148957 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4148961 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/system,/admin/mbeans,/admin/luke,/admin/threads,/config,/update/json,/replication,/update,/admin/properties,/admin/segments,/admin/file,/get,standard,/admin/ping,/update/json/docs,/admin/logging,/update/csv,/schema,/admin/plugins [junit4] 2> 4148963 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4148964 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4148964 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4148994 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 4148994 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 4148995 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=8, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 4149033 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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@b8635ff3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@70f67bcc; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 4149033 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 4149034 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@485261ec[collection1] main [junit4] 2> 4149034 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4149035 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4149035 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4149035 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4149035 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 4149036 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4149036 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4149036 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4149036 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 4149037 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 4149038 INFO (searcherExecutor-6225-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@485261ec[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4149040 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4149040 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4149040 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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> 4149040 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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 1509249722693451776 [junit4] 2> 4149040 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp 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 1509249722693451776 [junit4] 2> 4149041 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 4149041 INFO (coreLoadExecutor-6224-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 4149048 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp 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:48466/a_/p collection:control_collection shard:shard1 [junit4] 2> 4149052 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp 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> 4149056 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp 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/94328107485036550-core_node1-n_0000000000 [junit4] 2> 4149057 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 4149058 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4149058 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 4149059 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp 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> 4149059 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:48466/a_/p/collection1/ [junit4] 2> 4149059 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 4149059 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:48466/a_/p/collection1/ has no replicas [junit4] 2> 4149059 INFO (OverseerStateUpdate-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] 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> 4149059 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp 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:48466/a_/p/collection1/ shard1 [junit4] 2> 4149060 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4149061 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] 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> 4149064 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 4149067 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4149068 INFO (OverseerStateUpdate-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] 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:48466/a_/p";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 4149135 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4149152 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4149177 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] 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> 4149179 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 4149182 INFO (zkCallback-2156-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@78eebd3a name:ZooKeeperConnection Watcher:127.0.0.1:47415/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4149182 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4149182 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4149182 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4149184 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4149218 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:48466/a_/p/collection1/ and leader is http://127.0.0.1:48466/a_/p/collection1/ [junit4] 2> 4149218 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp 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:48466/a_/p [junit4] 2> 4149219 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 4149219 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 4149219 INFO (coreZkRegister-6218-thread-1-processing-n:127.0.0.1:48466_a_%2Fp r:core_node1 s:shard1 x:collection1 c:control_collection) [n:127.0.0.1:48466_a_%2Fp 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> 4149220 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4149221 INFO (OverseerStateUpdate-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:48466_a_%2Fp", [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"http://127.0.0.1:48466/a_/p";, [junit4] 2> "numShards":"2", [junit4] 2> "operation":"state", [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "state":"active", [junit4] 2> "roles":null, [junit4] 2> "core_node_name":"core_node1"} current state version: 3 [junit4] 2> 4149221 INFO (OverseerStateUpdate-94328107485036550-127.0.0.1:48466_a_%2Fp-n_0000000000) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "node_name":"127.0.0.1:48466_a_%2Fp", [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"http://127.0.0.1:48466/a_/p";, [junit4] 2> "numShards":"2", [junit4] 2> "operation":"state", [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "state":"active", [junit4] 2> "roles":null, [junit4] 2> "core_node_name":"core_node1"} [junit4] 2> 4149324 INFO (zkCallback-2156-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> 4149324 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] 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> 4149327 INFO (zkCallback-2156-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 4149327 INFO (zkCallback-2154-thread-1-processing-n:127.0.0.1:48466_a_%2Fp) [n:127.0.0.1:48466_a_%2Fp ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 4149557 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/shard-1-001/cores/collection1 [junit4] 2> 4149558 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] 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/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/shard-1-001 [junit4] 2> 4149559 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 4149619 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7468d509{/a_/p,null,AVAILABLE} [junit4] 2> 4149619 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@e7a7d94{HTTP/1.1}{127.0.0.1:56282} [junit4] 2> 4149620 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.e.j.s.Server Started @4152761ms [junit4] 2> 4149620 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solrconfig=solrconfig.xml, hostContext=/a_/p, solr.data.dir=hdfs://localhost:53601/hdfs__localhost_53601__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-5.3_solr_build_solr-core_test_J1_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001_tempDir-002_jetty1, hostPort=56282, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/shard-1-001/cores} [junit4] 2> 4149620 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@1fe91485 [junit4] 2> 4149620 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/J1/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_E1E4810DB75117FB-001/shard-1-001/' [junit4] 2> 4149638 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4149639 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4149640 INFO (zkCallback-2157-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@796b3bf0 name:ZooKeeperConnection Watcher:127.0.0.1:47415/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4149640 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4149641 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4149642 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4149642 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[E1E4810DB75117FB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/sol [...truncated too long message...] 1.call(HttpSolrClient.java:266) [junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:262) [junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:210) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.net.SocketException: Socket closed [junit4] 2> at java.net.SocketInputStream.socketRead0(Native Method) [junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:152) [junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:122) [junit4] 2> at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) [junit4] 2> at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) [junit4] 2> at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) [junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) [junit4] 2> at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) [junit4] 2> at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [junit4] 2> at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) [junit4] 2> at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) [junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) [junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) [junit4] 2> at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) [junit4] 2> at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) [junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) [junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:465) [junit4] 2> ... 7 more [junit4] 2> [junit4] 2> NOTE: test params are: codec=Asserting(Lucene53): {other_tl1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), id=PostingsFormat(name=Asserting), timestamp=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), _version_=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=BlockTreeOrds(blocksize=128), a_i1=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_l_dv=PostingsFormat(name=Asserting), range_facet_l=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), range_facet_i_dv=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), intDefault=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text=FST50}, docValues:{timestamp=DocValuesFormat(name=Lucene50), range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Lucene50)}, sim=RandomSimilarityProvider(queryNorm=false,coord=no): {}, locale=et, timezone=America/Argentina/Catamarca [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.7.0_72 (64-bit)/cpus=4,threads=281,free=51748584,total=480772096 [junit4] 2> 10893619 INFO (RecoveryThread-collection1) [n:127.0.0.1:52105__ c:collection1 s:shard2 r:core_node4 x:collection1] o.a.s.c.RecoveryStrategy Finished recovery process. [junit4] 2> NOTE: All tests run in this JVM: [TestFaceting, TestManagedSynonymFilterFactory, TestRemoteStreaming, HighlighterTest, ConcurrentDeleteAndCreateCollectionTest, PrimitiveFieldTypeTest, TestCloudInspectUtil, TestManagedSchema, TestFieldSortValues, UUIDFieldTest, UniqFieldsUpdateProcessorFactoryTest, TestFieldCollectionResource, TestBinaryField, TestPKIAuthenticationPlugin, TestRangeQuery, TermVectorComponentTest, TestArbitraryIndexDir, URLClassifyProcessorTest, ChaosMonkeyNothingIsSafeTest, TestObjectReleaseTracker, TestRebalanceLeaders, CloudMLTQParserTest, TestCollectionAPI, TestDFRSimilarityFactory, TestCodecSupport, IndexBasedSpellCheckerTest, TestCustomSort, MultiThreadedOCPTest, PingRequestHandlerTest, CurrencyFieldXmlFileTest, TestBadConfig, ZkNodePropsTest, TermsComponentTest, TestQuerySenderListener, SolrIndexConfigTest, SuggestComponentTest, LeaderElectionIntegrationTest, TestSolrIndexConfig, DistributedSpellCheckComponentTest, DistributedFacetPivotSmallAdvancedTest, DateMathParserTest, TestMinMaxOnMultiValuedField, AddSchemaFieldsUpdateProcessorFactoryTest, SignatureUpdateProcessorFactoryTest, TestJoin, TestRawResponseWriter, TestDynamicFieldCollectionResource, CoreAdminRequestStatusTest, TestRandomFaceting, CoreMergeIndexesAdminHandlerTest, TestCursorMarkWithoutUniqueKey, TestManagedResource, TestFieldResource, TestStressLucene, TestEmbeddedSolrServerConstructors, ExitableDirectoryReaderTest, TestChildDocTransformer, AddBlockUpdateTest, QueryElevationComponentTest, HdfsRecoveryZkTest, LeaderInitiatedRecoveryOnCommitTest, SuggesterWFSTTest, ClusterStateTest, TestSearcherReuse, TestConfigOverlay, TestPostingsSolrHighlighter, SaslZkACLProviderTest, DeleteInactiveReplicaTest, TestJmxIntegration, RankQueryTest, TestRandomDVFaceting, TestWordDelimiterFilterFactory, TestCloudManagedSchemaConcurrent, TestSchemaSimilarityResource, TestJmxMonitoredMap, HdfsRecoverLeaseTest, AnalysisErrorHandlingTest, SegmentsInfoRequestHandlerTest, TestStressUserVersions, TestFunctionQuery, TestCustomDocTransformer, TestSolrConfigHandlerCloud, MinimalSchemaTest, DefaultValueUpdateProcessorTest, TestSweetSpotSimilarityFactory, BasicDistributedZkTest, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest] [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.seed=E1E4810DB75117FB -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=et -Dtests.timezone=America/Argentina/Catamarca -Dtests.asserts=true -Dtests.file.encoding=UTF-8 [junit4] 2> 10893617 INFO (qtp207302141-13312) [n:127.0.0.1:53530__ ] o.a.s.h.a.CoreAdminHandler In WaitForState(recovering): collection=awholynewstresscollection_collection1_0, shard=shard1, thisCore=awholynewstresscollection_collection1_0_shard1_replica3, leaderDoesNotNeedRecovery=false, isLeader? false, live=true, checkLive=true, currentState=active, localState=active, nodeName=127.0.0.1:52105__, coreNodeName=core_node17, onlyIfActiveCheckResult=false, nodeProps: core_node17:{"state":"active","base_url":"http://127.0.0.1:52105/_","core":"awholynewstresscollection_collection1_0_shard1_replica2","node_name":"127.0.0.1:52105__"} [junit4] ERROR 0.00s J0 | CollectionsAPIDistributedZkTest (suite) <<< [junit4] > Throwable #1: java.lang.OutOfMemoryError: Java heap space [junit4] Completed [531/531] on J0 in 9416.43s, 1 test, 1 error <<< FAILURES! [...truncated 1 lines...] [junit4] JVM J0: stdout was not empty, see: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/solr/build/solr-core/test/temp/junit4-J0-20150811_172905_262.sysout [junit4] >>> JVM J0: stdout (verbatim) ---- [junit4] java.lang.OutOfMemoryError: Java heap space [junit4] Dumping heap to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-5.3/heapdumps/java_pid5852.hprof ... [junit4] Heap dump file created [632349419 bytes in 10.921 secs] [junit4] <<< JVM J0: EOF ---- [...truncated 12 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: 531 suites, 2104 tests, 1 suite-level error, 1 error, 1 failure, 26 ignored (14 assumptions) Total time: 285 minutes 55 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
