Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.x/70/
1 tests failed. FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Error from server at http://127.0.0.1:53178/_tub/cm: Could not fully remove collection: halfdeletedcollection2 Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:53178/_tub/cm: Could not fully remove collection: halfdeletedcollection2 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:590) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:259) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1599) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1553) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1608) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.deleteCollectionWithDownNodes(CollectionsAPIDistributedZkTest.java:348) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:185) 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:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1764) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:871) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:921) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:809) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:460) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:880) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:816) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:367) at java.lang.Thread.run(Thread.java:745) Build Log: [...truncated 12406 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/init-core-data-001 [junit4] 2> 4912771 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) [junit4] 2> 4912772 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_tub/cm [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 4912966 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 4912973 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4912975 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4913010 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-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_33066_hdfs____.z8nuu/webapp [junit4] 2> 4913164 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4913501 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:33066 [junit4] 2> 4913785 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4913785 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4913795 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-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_35465_datanode____.q5nz2g/webapp [junit4] 2> 4914145 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4914476 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:35465 [junit4] 2> 4914501 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 4914502 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 4914512 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-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_34328_datanode____.nrbyap/webapp [junit4] 2> 4914591 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 4914736 INFO (IPC Server handler 9 on 57698) [ ] BlockStateChange BLOCK* processReport: from storage DS-a1f4f331-4bce-41e4-93de-bbdab8dcff45 node DatanodeRegistration(127.0.0.1, datanodeUuid=622c74d9-95de-48ed-a213-08a43ff9b15b, infoPort=35465, ipcPort=41008, storageInfo=lv=-56;cid=testClusterID;nsid=1038933711;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 4914736 INFO (IPC Server handler 9 on 57698) [ ] BlockStateChange BLOCK* processReport: from storage DS-38e49647-d015-42ad-977a-8ffa5f261bea node DatanodeRegistration(127.0.0.1, datanodeUuid=622c74d9-95de-48ed-a213-08a43ff9b15b, infoPort=35465, ipcPort=41008, storageInfo=lv=-56;cid=testClusterID;nsid=1038933711;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 4915125 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[436A780E1D7592FB]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:34328 [junit4] 2> 4915259 INFO (IPC Server handler 4 on 57698) [ ] BlockStateChange BLOCK* processReport: from storage DS-e1d90173-63d2-4abf-9803-bd39baedc7d9 node DatanodeRegistration(127.0.0.1, datanodeUuid=2c05565b-badd-412f-8951-6d0848337463, infoPort=34328, ipcPort=42359, storageInfo=lv=-56;cid=testClusterID;nsid=1038933711;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 4915260 INFO (IPC Server handler 4 on 57698) [ ] BlockStateChange BLOCK* processReport: from storage DS-f625909c-2051-4887-8d4c-9392b09cbb58 node DatanodeRegistration(127.0.0.1, datanodeUuid=2c05565b-badd-412f-8951-6d0848337463, infoPort=34328, ipcPort=42359, storageInfo=lv=-56;cid=testClusterID;nsid=1038933711;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 4915571 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 4915589 INFO (Thread-93058) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4915589 INFO (Thread-93058) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 4915680 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.ZkTestServer start zk server on port:49695 [junit4] 2> 4915681 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4915700 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4915706 INFO (zkCallback-4766-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2446fa3c name:ZooKeeperConnection Watcher:127.0.0.1:49695 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4915706 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4915706 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4915706 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 4915708 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4915708 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4915709 INFO (zkCallback-4767-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@49271774 name:ZooKeeperConnection Watcher:127.0.0.1:49695/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4915709 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4915709 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4915709 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 4915710 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 4915710 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 4915711 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 4915711 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 4915712 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4915713 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 4915713 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 4915713 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4915714 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4915714 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 4915714 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 4915715 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 4915715 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 4915716 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 4915716 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 4915717 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 4915717 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4915717 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 4915718 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4915718 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4915719 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4915719 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 4915719 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4915720 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 4915720 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 4915781 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/cores/collection1 [junit4] 2> 4915782 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 4915888 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cd0dc24{/_tub/cm,null,AVAILABLE} [junit4] 2> 4915889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@7047707{HTTP/1.1,[http/1.1]}{127.0.0.1:35541} [junit4] 2> 4915889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.e.j.s.Server Started @4917988ms [junit4] 2> 4915889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:57698/hdfs__localhost_57698__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001_tempDir-002_control_data, hostContext=/_tub/cm, hostPort=35541, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/cores} [junit4] 2> 4915889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 4915889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001' [junit4] 2> 4915889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4915889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 4915889 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4916070 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4916122 INFO (zkCallback-4768-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4cdc7e88 name:ZooKeeperConnection Watcher:127.0.0.1:49695/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4916122 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4916122 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4916123 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4916123 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/solr.xml [junit4] 2> 4916128 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/cores [junit4] 2> 4916128 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.CoreContainer New CoreContainer 435567586 [junit4] 2> 4916128 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001] [junit4] 2> 4916128 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/lib [junit4] 2> 4916128 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] 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> 4916129 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4916129 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4916129 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4916129 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49695/solr [junit4] 2> 4916130 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 4916130 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4916130 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4916131 INFO (zkCallback-4771-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1b4c3284 name:ZooKeeperConnection Watcher:127.0.0.1:49695 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4916132 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4916132 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4916133 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$35/1629263601@4fe64da3 [junit4] 2> 4916133 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4916134 INFO (zkCallback-4772-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@527f4b79 name:ZooKeeperConnection Watcher:127.0.0.1:49695/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4916134 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4916135 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 4916136 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 4916138 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 4916140 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 4916141 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 4916142 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 4916143 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 4916143 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 4916144 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 4916145 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4916145 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 4916145 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 4916146 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 4916147 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 4916148 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4916148 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000 [junit4] 2> 4916148 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35541__tub%2Fcm [junit4] 2> 4916148 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 4916149 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.Overseer Overseer (id=95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) starting [junit4] 2> 4916150 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 4916152 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4916153 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 4916153 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 4916164 INFO (OverseerCollectionConfigSetProcessor-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 4916172 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35541__tub%2Fcm [junit4] 2> 4916173 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:35541__tub%2Fcm [junit4] 2> 4916173 INFO (zkCallback-4772-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.ZkStateReader A live node change: [WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes], has occurred - updating... (live nodes size: [0]) [junit4] 2> 4916173 INFO (zkCallback-4772-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 4916180 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 4916180 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 4916181 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/cores [junit4] 2> 4916181 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.CoreDescriptor Created CoreDescriptor: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, configSetProperties=configsetprops.json, collection=control_collection, coreNodeName=, dataDir=data/, shard=} [junit4] 2> 4916181 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/cores/collection1 [junit4] 2> 4916181 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 4916201 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 4916201 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 4916202 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.ZkController Collection control_collection not visible yet, but flagging it so a watch is registered when it becomes visible [junit4] 2> 4916202 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 4916202 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2 [junit4] 2> 4916202 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 4916203 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] 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:35541/_tub/cm";, [junit4] 2> "node_name":"127.0.0.1:35541__tub%2Fcm", [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> 4916203 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] 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:35541/_tub/cm";, [junit4] 2> "node_name":"127.0.0.1:35541__tub%2Fcm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4916203 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 4916203 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 4916204 INFO (zkCallback-4772-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm ] 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> 4916208 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 4916208 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 4916208 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 4916208 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 4916208 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 4916208 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 4916208 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/cores/collection1' [junit4] 2> 4916208 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4916209 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 4916209 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 4916213 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 4916215 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.1.0 [junit4] 2> 4916221 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 4916235 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 4916359 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4916372 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 4916373 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 4916387 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 4916388 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 4916389 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4916389 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4916390 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.ConfigSetProperties Did not find ConfigSet properties, assuming default properties: Can't find resource 'configsetprops.json' in classpath or '/configs/conf1', cwd=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2 [junit4] 2> 4916390 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4916390 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 4916390 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:57698/solr_hdfs_home [junit4] 2> 4916390 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 4916391 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/control-001/cores/collection1], dataDir=[hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/] [junit4] 2> 4916391 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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@7c9eb6a8 [junit4] 2> 4916391 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 4916411 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 4916412 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 4916417 WARN (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:57698/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 4916417 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 4916448 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 4916448 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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=7, maxMergeAtOnceExplicit=8, maxMergedSegmentMB=64.328125, floorSegmentMB=1.5263671875, forceMergeDeletesPctAllowed=15.945864111927868, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7263772451167029 [junit4] 2> 4916478 INFO (IPC Server handler 1 on 57698) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:48520 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-e1d90173-63d2-4abf-9803-bd39baedc7d9:NORMAL:127.0.0.1:35903|RBW], ReplicaUnderConstruction[[DISK]DS-38e49647-d015-42ad-977a-8ffa5f261bea:NORMAL:127.0.0.1:48520|FINALIZED]]} size 0 [junit4] 2> 4916479 INFO (IPC Server handler 4 on 57698) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:35903 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-e1d90173-63d2-4abf-9803-bd39baedc7d9:NORMAL:127.0.0.1:35903|RBW], ReplicaUnderConstruction[[DISK]DS-38e49647-d015-42ad-977a-8ffa5f261bea:NORMAL:127.0.0.1:48520|FINALIZED]]} size 0 [junit4] 2> 4916481 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5095d6d7; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 4916481 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 4916482 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:35541__tub%2Fcm 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:57698/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 4916484 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4916484 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 4916484 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4916484 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4916484 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4916484 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916484 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916484 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916484 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916484 WARN (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,args = {defaults={a=A,b=B}}} [junit4] 2> 4916486 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4916486 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4916487 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4916487 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 4916490 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/update/json/docs,/admin/luke,/export,/get,/admin/properties,/update/json,/admin/threads,/dump,/update/csv,/sql,/graph,/admin/segments,/admin/system,/replication,/config,/stream,/schema,/admin/plugins,/admin/logging,/admin/ping,/update,/admin/file [junit4] 2> 4916490 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916490 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916490 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4916490 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2 [junit4] 2> 4916498 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4916498 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4916498 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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=7, maxMergeAtOnceExplicit=3, maxMergedSegmentMB=26.013671875, floorSegmentMB=0.740234375, forceMergeDeletesPctAllowed=27.060363300707873, segmentsPerTier=22.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4279514567905953 [junit4] 2> 4916502 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=NRTCachingDirectory(HdfsDirectory@hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5095d6d7; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 4916502 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 4916502 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5bce5e2a[collection1] main] [junit4] 2> 4916502 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916502 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916502 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916503 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4916503 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 4916503 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916503 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916503 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916503 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 4916503 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 4916504 INFO (searcherExecutor-9164-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5bce5e2a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4916504 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916504 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 65536 version buckets from index [junit4] 2> 4916504 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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> 4916504 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm 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 1535051360719863808 [junit4] 2> 4916506 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 1.0ms to seed version buckets with highest version 1535051360719863808 [junit4] 2> 4916506 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 4916506 INFO (coreLoadExecutor-9163-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 4916506 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm 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:35541/_tub/cm collection:control_collection shard:shard1 [junit4] 2> 4916507 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm 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> 4916509 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm 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/95940709985091588-core_node1-n_0000000000 [junit4] 2> 4916510 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Running the leader process for shard=shard1 and weAreReplacement=false and leaderVoteWait=180000 [junit4] 2> 4916511 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 4916511 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] 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> 4916511 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm 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> 4916511 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:35541/_tub/cm/collection1/ [junit4] 2> 4916511 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 4916511 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:35541/_tub/cm/collection1/ has no replicas [junit4] 2> 4916512 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4916513 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/95940709985091588-core_node1-n_0000000000 [junit4] 2> 4916513 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm 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:35541/_tub/cm/collection1/ shard1 [junit4] 2> 4916514 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] 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:35541/_tub/cm";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 4916615 INFO (zkCallback-4772-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm ] 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> 4916664 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:35541/_tub/cm/collection1/ and leader is http://127.0.0.1:35541/_tub/cm/collection1/ [junit4] 2> 4916664 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm 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:35541/_tub/cm [junit4] 2> 4916664 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 4916664 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 4916664 INFO (coreZkRegister-9157-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:35541__tub%2Fcm 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> 4916665 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "dataDir":"hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35541/_tub/cm";, [junit4] 2> "node_name":"127.0.0.1:35541__tub%2Fcm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "ulogDir":"hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/tlog"} current state version: 2 [junit4] 2> 4916665 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "dataDir":"hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:35541/_tub/cm";, [junit4] 2> "node_name":"127.0.0.1:35541__tub%2Fcm", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "ulogDir":"hdfs://localhost:57698/solr_hdfs_home/control_collection/core_node1/data/tlog"} [junit4] 2> 4916704 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4916720 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4916744 INFO (zkCallback-4776-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5f6c9286 name:ZooKeeperConnection Watcher:127.0.0.1:49695/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4916744 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4916745 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4916745 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4916746 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 4916747 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 4916747 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4916747 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 4916747 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4916766 INFO (zkCallback-4772-thread-1-processing-n:127.0.0.1:35541__tub%2Fcm) [n:127.0.0.1:35541__tub%2Fcm ] 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> 4916769 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4916776 INFO (zkCallback-4776-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> 4916788 INFO (zkCallback-4777-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@ae60476 name:ZooKeeperConnection Watcher:127.0.0.1:49695/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4916788 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4916789 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4916790 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] 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> 4916790 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 4916970 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 4917051 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/shard-1-001/cores/collection1 [junit4] 2> 4917051 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/shard-1-001 [junit4] 2> 4917052 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 4917057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@38c822ad{/_tub/cm,null,AVAILABLE} [junit4] 2> 4917057 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@2242dcba{HTTP/1.1,[http/1.1]}{127.0.0.1:53178} [junit4] 2> 4917058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.e.j.s.Server Started @4919156ms [junit4] 2> 4917058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:57698/hdfs__localhost_57698__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.x_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/_tub/cm, hostPort=53178, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/shard-1-001/cores} [junit4] 2> 4917058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 4917058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/shard-1-001' [junit4] 2> 4917058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4917058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 4917058 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4917059 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4917064 INFO (zkCallback-4778-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3bc791fc name:ZooKeeperConnection Watcher:127.0.0.1:49695/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4917064 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4917065 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4917065 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4917066 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/shard-1-001/solr.xml [junit4] 2> 4917070 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/shard-1-001/cores [junit4] 2> 4917070 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.CoreContainer New CoreContainer 412339367 [junit4] 2> 4917070 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/shard-1-001] [junit4] 2> 4917070 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_436A780E1D7592FB-001/shard-1-001/lib [junit4] 2> 4917071 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] 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> 4917072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4917072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4917072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4917072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:49695/solr [junit4] 2> 4917072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 4917072 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 4917073 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4917074 INFO (zkCallback-4781-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@572e8d44 name:ZooKeeperConnection Watcher:127.0.0.1:49695 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4917075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4917075 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 4917076 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$35/1629263601@439e2ecb [junit4] 2> 4917076 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 4917077 INFO (zkCallback-4782-thread-1-processing-n:127.0.0.1:53178__tub%2Fcm) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@6a16aee2 name:ZooKeeperConnection Watcher:127.0.0.1:49695/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4917077 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 4917081 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 4917082 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.c.ZkStateReader Loaded empty cluster properties [junit4] 2> 4917082 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 4917084 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4917085 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95940709985091593-127.0.0.1:53178__tub%2Fcm-n_0000000001 [junit4] 2> 4917085 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.LeaderElector Watching path /overseer_elect/election/95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000 to know if I could be the leader [junit4] 2> 4917086 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[436A780E1D7592FB]) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.ZkController Publish node=127.0.0.1:53178__tub%2Fcm as DOWN [junit4] 2> 4917087 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"downnode", [junit4] 2> "node_name":"127.0.0.1:53178__tub%2Fcm"} current state version: 3 [junit4] 2> 4917087 INFO (OverseerStateUpdate-95940709985091588-127.0.0.1:35541__tub%2Fcm-n_0000000000) [n:127.0.0.1:35541__tub%2Fcm ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:53178__tub%2Fcm [junit4] 2> 4917088 INFO (zkCallback-4776-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> 4917088 INFO (zkCallback-4782-thread-1-processing-n:127.0.0.1:53178__tub%2Fcm) [n:127.0.0.1:53178__tub%2Fcm ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:Node [...truncated too long message...] g-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-collective-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-event-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-exception-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-journal-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-normalization-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-operational-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-referral-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-schema-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-subtree-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-interceptors-trigger-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-jdbm1-2.0.0-M2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-kerberos-codec-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-ldif-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-mavibot-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-kerberos-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-ldap-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-protocol-shared-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/apacheds-xdbm-partition-2.0.0-M15.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/api-all-1.0.0-M20.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/bcprov-jdk15-1.45.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/cglib-nodep-2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-collections-3.2.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/commons-math3-3.4.1.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/easymock-3.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/ehcache-core-2.4.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-common-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-hdfs-2.6.0-tests.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/hadoop-minikdc-2.6.0.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-annotations-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jackson-databind-2.5.4.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-core-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jersey-server-1.9.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/jetty-util-6.1.26.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mina-core-2.0.0-M5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/mockito-core-1.9.5.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/objenesis-1.2.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/presto-parser-0.122.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/core/test-lib/slice-0.10.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/analysis/icu/lucene-analyzers-icu-6.1.0-SNAPSHOT.jar:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/contrib/solr-analysis-extras/classes/java:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/build/queryparser/classes/test:/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/contrib/analysis-extras/lib/icu4j-56.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.3.4.jar com.carrotsearch.ant.tasks.junit4.slave.SlaveMainSafe -eventsfile /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J1-20160522_162218_426.events @/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build/solr-core/test/temp/junit4-J1-20160522_162218_426.suites -stdin [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:1501) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4.access$000(JUnit4.java:121) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:955) [junit4] at com.carrotsearch.ant.tasks.junit4.JUnit4$2.call(JUnit4.java:952) [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-6.x/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/solr/common-build.xml:529: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:1427: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.x/lucene/common-build.xml:984: 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: 407 minutes 52 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts No prior successful build to compare, so performing full copy of artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
