Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/725/
2 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsChaosMonkeySafeLeaderTest.test Error Message: shard2 is not consistent. Got 804 from http://127.0.0.1:47293/g_/e/collection1lastClient and got 234 from http://127.0.0.1:54250/g_/e/collection1 Stack Trace: java.lang.AssertionError: shard2 is not consistent. Got 804 from http://127.0.0.1:47293/g_/e/collection1lastClient and got 234 from http://127.0.0.1:54250/g_/e/collection1 at __randomizedtesting.SeedInfo.seed([FA71FEA7F6F8C869:7225C17D5804A591]:0) at org.junit.Assert.fail(Assert.java:93) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1244) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.checkShardConsistency(AbstractFullDistribZkTestBase.java:1223) at org.apache.solr.cloud.ChaosMonkeySafeLeaderTest.test(ChaosMonkeySafeLeaderTest.java:165) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) 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:960) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=7135, name=collection2, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=7135, name=collection2, state=RUNNABLE, group=TGRP-HdfsCollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:55659: Could not find collection : awholynewstresscollection_collection2_0 at __randomizedtesting.SeedInfo.seed([FA71FEA7F6F8C869]: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:1086) 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) Build Log: [...truncated 10129 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/init-core-data-001 [junit4] 2> 530345 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 530345 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 530409 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 530441 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 530443 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 530467 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-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_44000_hdfs____.hxquna/webapp [junit4] 2> 530627 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 531285 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:44000 [junit4] 2> 531459 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 531460 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 531499 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-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_38191_datanode____a3rbsb/webapp [junit4] 2> 531624 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 532574 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:38191 [junit4] 2> 532666 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 532668 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 532696 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-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_44910_datanode____.hp4k1t/webapp [junit4] 2> 532796 INFO (IPC Server handler 3 on 48760) [ ] BlockStateChange BLOCK* processReport: from storage DS-4d7af7b0-176d-4fa0-a55a-f912d5f0623d node DatanodeRegistration(127.0.0.1, datanodeUuid=b1e3b272-85ef-457a-b9d2-cd5a2fdadad8, infoPort=38191, ipcPort=41570, storageInfo=lv=-56;cid=testClusterID;nsid=1139124183;c=0), blocks: 0, hasStaleStorages: true, processing time: 1 msecs [junit4] 2> 532797 INFO (IPC Server handler 3 on 48760) [ ] BlockStateChange BLOCK* processReport: from storage DS-9d8a262c-435f-4166-a67f-175519df7756 node DatanodeRegistration(127.0.0.1, datanodeUuid=b1e3b272-85ef-457a-b9d2-cd5a2fdadad8, infoPort=38191, ipcPort=41570, storageInfo=lv=-56;cid=testClusterID;nsid=1139124183;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 532874 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 533580 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[FA71FEA7F6F8C869]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:44910 [junit4] 2> 533676 INFO (IPC Server handler 8 on 48760) [ ] BlockStateChange BLOCK* processReport: from storage DS-cb79b499-4f86-4a6c-b72f-cae4ac5c72cf node DatanodeRegistration(127.0.0.1, datanodeUuid=4a5e8d2b-d3ec-456d-9402-1790f8a53385, infoPort=44910, ipcPort=57436, storageInfo=lv=-56;cid=testClusterID;nsid=1139124183;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 533677 INFO (IPC Server handler 8 on 48760) [ ] BlockStateChange BLOCK* processReport: from storage DS-4aef47bd-ca0f-4d04-baf6-80ad44d3e2c7 node DatanodeRegistration(127.0.0.1, datanodeUuid=4a5e8d2b-d3ec-456d-9402-1790f8a53385, infoPort=44910, ipcPort=57436, storageInfo=lv=-56;cid=testClusterID;nsid=1139124183;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 533757 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 533758 INFO (Thread-4671) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 533758 INFO (Thread-4671) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 533858 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.ZkTestServer start zk server on port:33369 [junit4] 2> 533858 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 533859 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 533863 INFO (zkCallback-238-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4f19f67b name:ZooKeeperConnection Watcher:127.0.0.1:33369 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 533863 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 533863 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 533863 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 533868 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 533869 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 533877 INFO (zkCallback-239-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@16cf0184 name:ZooKeeperConnection Watcher:127.0.0.1:33369/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 533879 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 533879 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 533879 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 533882 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 533884 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 533885 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 533888 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 533888 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 533891 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 533892 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 533894 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 533894 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 533896 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 533897 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 533898 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 533898 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 533900 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 533900 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 533902 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 533903 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 533904 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 533904 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 533906 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 533907 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 533909 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 533909 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 533912 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 533912 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 533915 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 533917 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 533924 INFO (zkCallback-240-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7d2dd4d8 name:ZooKeeperConnection Watcher:127.0.0.1:33369 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 533924 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 533925 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 533925 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 533928 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 533928 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 533930 INFO (zkCallback-241-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@58ef5ac0 name:ZooKeeperConnection Watcher:127.0.0.1:33369/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 533930 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 533930 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 533931 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf2/solrconfig.xml [junit4] 2> 533931 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.xml [junit4] 2> 533935 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf2/schema.xml [junit4] 2> 533935 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/schema.xml [junit4] 2> 533938 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf2/enumsConfig.xml [junit4] 2> 533938 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/enumsConfig.xml [junit4] 2> 533941 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 533941 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 533943 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf2/stopwords.txt [junit4] 2> 533943 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/stopwords.txt [junit4] 2> 533945 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf2/protwords.txt [junit4] 2> 533945 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/protwords.txt [junit4] 2> 533947 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf2/currency.xml [junit4] 2> 533947 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/currency.xml [junit4] 2> 533948 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf2/open-exchange-rates.json [junit4] 2> 533949 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/open-exchange-rates.json [junit4] 2> 533951 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 533951 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/mapping-ISOLatin1Accent.txt [junit4] 2> 533953 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf2/old_synonyms.txt [junit4] 2> 533953 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/old_synonyms.txt [junit4] 2> 533956 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf2/synonyms.txt [junit4] 2> 533956 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/synonyms.txt [junit4] 2> 533960 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/elevate.xml to /configs/conf2/elevate.xml [junit4] 2> 533960 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf2/elevate.xml [junit4] 2> 534381 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/cores/collection1 [junit4] 2> 534383 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 534394 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5316231f{/,null,AVAILABLE} [junit4] 2> 534394 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.e.j.s.ServerConnector Started ServerConnector@d8cff2f{HTTP/1.1}{127.0.0.1:55659} [junit4] 2> 534394 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.e.j.s.Server Started @537813ms [junit4] 2> 534394 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:48760/hdfs__localhost_48760__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001_tempDir-002_control_data, hostContext=/, hostPort=55659, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/cores} [junit4] 2> 534395 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 534395 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/' [junit4] 2> 534416 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/solr.xml [junit4] 2> 534421 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/cores [junit4] 2> 534421 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.CoreContainer New CoreContainer 1611399059 [junit4] 2> 534421 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/] [junit4] 2> 534422 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/lib [junit4] 2> 534422 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.SolrResourceLoader Can't find (or read) directory to add to classloader: lib (resolved as: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/lib). [junit4] 2> 534436 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] 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> 534442 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 534443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 534443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 534443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33369/solr [junit4] 2> 534443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 534443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 534449 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 534451 INFO (zkCallback-243-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3898cef3 name:ZooKeeperConnection Watcher:127.0.0.1:33369 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 534452 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 534452 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 534459 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 534463 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4f3e194e name:ZooKeeperConnection Watcher:127.0.0.1:33369/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 534463 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 534465 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 534467 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 534469 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 534471 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 534473 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 534475 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 534479 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 534480 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 534481 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:55659_ [junit4] 2> 534482 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:55659_ [junit4] 2> 534483 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 534484 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 534487 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 534488 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/94075170932457477-127.0.0.1:55659_-n_0000000000 [junit4] 2> 534489 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:55659_ [junit4] 2> 534489 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 534490 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.Overseer Overseer (id=94075170932457477-127.0.0.1:55659_-n_0000000000) starting [junit4] 2> 534492 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 534499 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 534513 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 534513 INFO (OverseerCollectionProcessor-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.OverseerCollectionProcessor Process current queue of collection creations [junit4] 2> 534514 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 534519 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 534519 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 534520 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.CoresLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/cores [junit4] 2> 534521 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.SolrCore Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/cores/collection1, collection=control_collection, absoluteInstDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/cores/collection1/, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 534521 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.CoresLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/cores/collection1/ [junit4] 2> 534521 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.c.CoresLocator Found 1 core definitions [junit4] 2> 534525 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 534525 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 534526 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 534530 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 534532 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55659", [junit4] 2> "node_name":"127.0.0.1:55659_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 534533 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55659", [junit4] 2> "node_name":"127.0.0.1:55659_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 534533 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 534533 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 534534 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] 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> 534535 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 1 [junit4] 2> 534543 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 534544 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [n:127.0.0.1:55659_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 535527 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 535527 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 535527 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 535527 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from:/collections/control_collection [junit4] 2> 535528 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 535528 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/cores/collection1/' [junit4] 2> 535549 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 535554 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 535563 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 535574 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.Config Loaded SolrConfig: solrconfig.xml [junit4] 2> 535576 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.IndexSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 535583 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 535715 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 535728 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 535729 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 535744 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 535747 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 535750 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 535751 WARN (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 535752 WARN (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 535752 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 535752 WARN (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Unknown key IMPORTANT NOTE [junit4] 2> 535753 WARN (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Expected key, got STRING [junit4] 2> 535753 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 535754 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 535754 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:48760/solr_hdfs_home [junit4] 2> 535754 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 535754 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/control-001/cores/collection1/], dataDir=[null] [junit4] 2> 535754 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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@27705dba [junit4] 2> 535757 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:48760/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 535775 WARN (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 540779 WARN (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsDirectory The NameNode is in SafeMode - Solr will wait 5 seconds and try again. [junit4] 2> 545782 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:48760/solr_hdfs_home/control_collection/core_node1/data for locality metrics. [junit4] 2> 545782 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:48760/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 545783 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:48760/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 545792 WARN (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:48760/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 545792 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:48760/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 545808 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.h.HdfsLocalityReporter Registering direcotry hdfs://localhost:48760/solr_hdfs_home/control_collection/core_node1/data/index for locality metrics. [junit4] 2> 545808 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:48760/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 545808 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 545873 INFO (IPC Server handler 1 on 48760) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:41445 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-4aef47bd-ca0f-4d04-baf6-80ad44d3e2c7:NORMAL:127.0.0.1:35043|RBW], ReplicaUnderConstruction[[DISK]DS-4d7af7b0-176d-4fa0-a55a-f912d5f0623d:NORMAL:127.0.0.1:41445|FINALIZED]]} size 0 [junit4] 2> 545887 INFO (IPC Server handler 9 on 48760) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:35043 is added to blk_1073741825_1001 size 71 [junit4] 2> 545890 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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@b7d3680e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7cadd9f6; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 545890 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 545893 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:55659_ 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:48760/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 545896 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 545896 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 545896 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 545897 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 545897 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 545897 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545897 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545897 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545897 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545899 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 545900 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 545901 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 545902 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 545905 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 545906 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545906 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545906 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545916 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Hard AutoCommit: disabled [junit4] 2> 545917 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore Soft AutoCommit: disabled [junit4] 2> 545918 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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=48, maxMergeAtOnceExplicit=35, maxMergedSegmentMB=95.3486328125, floorSegmentMB=0.5048828125, forceMergeDeletesPctAllowed=6.864399144476847, segmentsPerTier=11.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 545927 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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@b7d3680e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7cadd9f6; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 545927 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore newest commit generation = 1 [junit4] 2> 545928 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening Searcher@6876d10a[collection1] main [junit4] 2> 545928 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545929 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545929 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545929 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 545929 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 545929 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545930 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545930 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545930 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 545930 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 545931 INFO (searcherExecutor-1033-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6876d10a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 545948 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545948 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545948 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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> 545949 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ 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 1505202747701460992 [junit4] 2> 545949 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1 ms to seed version buckets with highest version 1505202747701460992 [junit4] 2> 545949 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 545949 INFO (coreLoadExecutor-1032-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 545950 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ 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:55659 collection:control_collection shard:shard1 [junit4] 2> 545951 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ 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> 545954 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ 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/94075170932457477-core_node1-n_0000000000 [junit4] 2> 545955 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard shard1 [junit4] 2> 545956 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 545957 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 545957 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] 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> 545957 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ 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> 545958 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:55659/collection1/ [junit4] 2> 545958 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] 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> 545959 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 545959 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:55659/collection1/ has no replicas [junit4] 2> 545959 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ 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:55659/collection1/ shard1 [junit4] 2> 545959 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 545964 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 2 [junit4] 2> 545966 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 545967 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] 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:55659", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 546049 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 546055 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 546071 INFO (zkCallback-246-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6c1b994a name:ZooKeeperConnection Watcher:127.0.0.1:33369/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 546071 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 546072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 546072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 546075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 546075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 546075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 546081 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] 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> 546083 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 546089 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 546104 INFO (zkCallback-246-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> 546107 INFO (zkCallback-246-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 3 [junit4] 2> 546117 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:55659/collection1/ and leader is http://127.0.0.1:55659/collection1/ [junit4] 2> 546117 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ 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:55659 [junit4] 2> 546118 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 546118 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 546118 INFO (coreZkRegister-1026-thread-1-processing-n:127.0.0.1:55659_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:55659_ 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> 546119 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 546120 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55659", [junit4] 2> "node_name":"127.0.0.1:55659_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 546120 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.o.ReplicaMutator Update state numShards=2 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:55659", [junit4] 2> "node_name":"127.0.0.1:55659_", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 546122 INFO (zkCallback-247-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@7f5b8aaf name:ZooKeeperConnection Watcher:127.0.0.1:33369/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 546122 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 546123 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 546124 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.DistributedQueue NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 546127 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 3 [junit4] 2> 546127 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 546128 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] 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> 546129 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 546131 INFO (zkCallback-246-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> 546132 INFO (zkCallback-246-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 4 [junit4] 2> 546232 INFO (OverseerStateUpdate-94075170932457477-127.0.0.1:55659_-n_0000000000) [n:127.0.0.1:55659_ ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 546233 INFO (zkCallback-246-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> 546233 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] 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> 546234 INFO (zkCallback-246-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 546235 INFO (zkCallback-244-thread-1-processing-n:127.0.0.1:55659_) [n:127.0.0.1:55659_ ] o.a.s.c.c.ZkStateReader Updated cluster state version to 5 [junit4] 2> 546565 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/shard-1-001/cores/collection1 [junit4] 2> 546566 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/shard-1-001 [junit4] 2> 546580 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.e.j.s.Server jetty-9.2.11.v20150529 [junit4] 2> 546624 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4f19999b{/,null,AVAILABLE} [junit4] 2> 546625 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.e.j.s.ServerConnector Started ServerConnector@1f3069ed{HTTP/1.1}{127.0.0.1:45967} [junit4] 2> 546625 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.e.j.s.Server Started @550044ms [junit4] 2> 546625 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:48760/hdfs__localhost_48760__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=45967, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/shard-1-001/cores} [junit4] 2> 546625 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 546626 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/shard-1-001/' [junit4] 2> 546648 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/shard-1-001/solr.xml [junit4] 2> 546653 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.CoresLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/shard-1-001/cores [junit4] 2> 546653 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.CoreContainer New CoreContainer 1213204579 [junit4] 2> 546653 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C869-001/shard-1-001/] [junit4] 2> 546653 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[FA71FEA7F6F8C869]) [ ] o.a.s.c.CoreContainer loading shared library: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_FA71FEA7F6F8C [...truncated too long message...] ctive-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/commons-collections-3.2.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jcl-over-slf4j-1.7.7.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/presto-parser-0.108.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/build/analysis/icu/lucene-analyzers-icu-6.0.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/contrib/analysis-extras/lib/icu4j-54.1.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-launcher.jar:/x1/jenkins/.ant/lib/ivy-2.3.0.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-antlr.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-regexp.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-resolver.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-net.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jmf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-oro.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-commons-logging.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jsch.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit4.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-javamail.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jai.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-jdepend.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-junit.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bsf.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-swing.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-netrexx.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-bcel.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-testutil.jar:/home/jenkins/jenkins-slave/tools/hudson.tasks.Ant_AntInstallation/ant-1.8.2/lib/ant-apache-log4j.jar:/x1/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/lib/tools.jar:/x1/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.1.13.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J1-20150628_022412_041.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/temp/junit4-J1-20150628_022412_041.suites [junit4] ERROR: JVM J1 ended with an exception: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.executeSlave(JUnit4.java:1504) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:133) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:964) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:961) [junit4] at java.util.concurrent.FutureTask.run(FutureTask.java:266) [junit4] at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [junit4] at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [junit4] at java.lang.Thread.run(Thread.java:745) BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:533: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:474: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:61: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/extra-targets.xml:39: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:230: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:513: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1426: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:981: At least one slave process threw an exception, first: Quit event not received from the forked process? This may indicate JVM crash or runner bugs. Total time: 178 minutes 33 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org