Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.0/16/
2 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Captured an uncaught exception in thread: Thread[id=4632, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Stack Trace: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=4632, name=collection1, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38813/fw_w/g: Could not find collection : awholynewstresscollection_collection1_0 at __randomizedtesting.SeedInfo.seed([21D3B069732F15C5]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:997) FAILED: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest.test Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:40269/ho_urd Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:40269/ho_urd at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:588) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.makeRequest(CollectionsAPIDistributedZkTest.java:400) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testErrorHandling(CollectionsAPIDistributedZkTest.java:458) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:179) 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) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:482) ... 47 more Build Log: [...truncated 11211 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/init-core-data-001 [junit4] 2> 418830 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 418831 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /ho_urd/ [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 418883 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 418888 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 418891 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 418906 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-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_46791_hdfs____3aap9z/webapp [junit4] 2> 419050 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 419405 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:46791 [junit4] 2> 419541 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 419542 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 419561 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-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_37578_datanode____u01as9/webapp [junit4] 2> 419651 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 420514 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:37578 [junit4] 2> 420555 WARN (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 420556 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 420582 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-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_51244_datanode____o36m0t/webapp [junit4] 2> 420666 INFO (IPC Server handler 4 on 38992) [ ] BlockStateChange BLOCK* processReport: from storage DS-c41db882-7d12-44ca-8beb-e7462c3d429c node DatanodeRegistration(127.0.0.1, datanodeUuid=6a82eeec-5809-4ed0-bb91-a6e00e4257f9, infoPort=37578, ipcPort=48904, storageInfo=lv=-56;cid=testClusterID;nsid=2144198505;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 420666 INFO (IPC Server handler 4 on 38992) [ ] BlockStateChange BLOCK* processReport: from storage DS-4f80829f-dca2-4408-b178-7a4db69c9c4d node DatanodeRegistration(127.0.0.1, datanodeUuid=6a82eeec-5809-4ed0-bb91-a6e00e4257f9, infoPort=37578, ipcPort=48904, storageInfo=lv=-56;cid=testClusterID;nsid=2144198505;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 420726 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 420934 INFO (SUITE-HdfsCollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:51244 [junit4] 2> 421049 INFO (IPC Server handler 0 on 38992) [ ] BlockStateChange BLOCK* processReport: from storage DS-2b2c137a-5359-4692-96a4-8ac420719c6d node DatanodeRegistration(127.0.0.1, datanodeUuid=156edfd6-30d3-4258-9f8d-5b25fa31f0bd, infoPort=51244, ipcPort=48392, storageInfo=lv=-56;cid=testClusterID;nsid=2144198505;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 421050 INFO (IPC Server handler 0 on 38992) [ ] BlockStateChange BLOCK* processReport: from storage DS-dda31474-047f-4e68-a9d3-258c88e6eb6e node DatanodeRegistration(127.0.0.1, datanodeUuid=156edfd6-30d3-4258-9f8d-5b25fa31f0bd, infoPort=51244, ipcPort=48392, storageInfo=lv=-56;cid=testClusterID;nsid=2144198505;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 421172 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 421178 INFO (Thread-2186) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 421178 INFO (Thread-2186) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 421278 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.ZkTestServer start zk server on port:42291 [junit4] 2> 421278 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 421293 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 421301 INFO (zkCallback-550-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1637a7ce name:ZooKeeperConnection Watcher:127.0.0.1:42291 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 421302 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 421302 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 421302 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 421308 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 421325 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 421326 INFO (zkCallback-551-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@59cbad12 name:ZooKeeperConnection Watcher:127.0.0.1:42291/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 421326 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 421326 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 421326 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 421327 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 421327 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 421328 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 421328 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 421328 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 421329 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 421329 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 421330 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 421330 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 421331 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 421331 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 421334 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 421334 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 421334 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 421334 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 421335 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 421335 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 421335 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 421335 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 421336 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 421336 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 421337 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 421337 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 421337 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 421337 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 421405 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/cores/collection1 [junit4] 2> 421408 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 421424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ef3c4a7{/ho_urd,null,AVAILABLE} [junit4] 2> 421424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.e.j.s.ServerConnector Started ServerConnector@473edeaf{HTTP/1.1,[http/1.1]}{127.0.0.1:51459} [junit4] 2> 421424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.e.j.s.Server Started @423458ms [junit4] 2> 421424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:38992/hdfs__localhost_38992__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.0_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001_tempDir-002_control_data, hostContext=/ho_urd, hostPort=51459, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/cores} [junit4] 2> 421424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 421424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001' [junit4] 2> 421424 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 421425 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 421425 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 421425 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 421431 INFO (zkCallback-552-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2c574b50 name:ZooKeeperConnection Watcher:127.0.0.1:42291/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 421431 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 421431 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 421432 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 421432 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/solr.xml [junit4] 2> 421435 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/cores [junit4] 2> 421436 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.CoreContainer New CoreContainer 1499965252 [junit4] 2> 421436 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001] [junit4] 2> 421436 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/lib [junit4] 2> 421436 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] 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> 421437 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 421437 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 421437 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 421441 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42291/solr [junit4] 2> 421441 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 421442 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 421442 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 421443 INFO (zkCallback-555-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@303f0453 name:ZooKeeperConnection Watcher:127.0.0.1:42291 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 421443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 421443 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 421444 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$86/693312754@456532f4 [junit4] 2> 421445 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 421445 INFO (zkCallback-556-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@67cf7bcd name:ZooKeeperConnection Watcher:127.0.0.1:42291/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 421446 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 421446 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 421447 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 421449 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 421450 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 421451 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 421452 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 421453 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 421454 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 421454 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 421455 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 421455 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (0) [junit4] 2> 421456 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 421457 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 421458 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 421458 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000 [junit4] 2> 421459 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51459_ho_urd [junit4] 2> 421459 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 421459 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.Overseer Overseer (id=95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) starting [junit4] 2> 421460 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 421464 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 421465 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51459_ho_urd [junit4] 2> 421465 INFO (OverseerCollectionConfigSetProcessor-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 421466 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:51459_ho_urd [junit4] 2> 421466 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 421467 INFO (zkCallback-556-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd ] 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> 421475 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 421475 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 421475 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/cores [junit4] 2> 421476 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] 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> 421476 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/cores/collection1 [junit4] 2> 421476 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 421481 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2 [junit4] 2> 421481 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:51459_ho_urd ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 421482 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 421485 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 421485 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 421486 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 421486 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.c.ZkStateReader addZkWatch [control_collection] [junit4] 2> 421487 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] 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:51459/ho_urd";, [junit4] 2> "node_name":"127.0.0.1:51459_ho_urd", [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> 421487 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Deleting data for [control_collection] [junit4] 2> 421488 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] 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:51459/ho_urd";, [junit4] 2> "node_name":"127.0.0.1:51459_ho_urd", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 421489 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 421489 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 421490 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 421492 INFO (zkCallback-556-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd ] 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> 422489 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 422489 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 422489 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 422489 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 422490 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 422490 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/cores/collection1' [junit4] 2> 422490 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 422490 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 422491 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 422495 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 422498 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.1 [junit4] 2> 422514 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 422518 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 422598 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 422602 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 422602 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 422608 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 422609 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 422611 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 422611 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 422613 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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.0/solr/build/solr-core/test/J2 [junit4] 2> 422613 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 422613 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 422613 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:38992/solr_hdfs_home [junit4] 2> 422613 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 422613 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/control-001/cores/collection1], dataDir=[hdfs://localhost:38992/solr_hdfs_home/control_collection/core_node1/data/] [junit4] 2> 422613 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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@13248cc3 [junit4] 2> 422613 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:38992/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 422645 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:38992/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 422645 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:38992/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 422651 WARN (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:38992/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 422652 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:38992/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 422659 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:38992/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 422659 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=3, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5467696557503788] [junit4] 2> 422723 INFO (IPC Server handler 9 on 38992) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42103 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-2b2c137a-5359-4692-96a4-8ac420719c6d:NORMAL:127.0.0.1:48963|RBW], ReplicaUnderConstruction[[DISK]DS-4f80829f-dca2-4408-b178-7a4db69c9c4d:NORMAL:127.0.0.1:42103|RBW]]} size 0 [junit4] 2> 422725 INFO (IPC Server handler 7 on 38992) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:48963 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-4f80829f-dca2-4408-b178-7a4db69c9c4d:NORMAL:127.0.0.1:42103|RBW], ReplicaUnderConstruction[[DISK]DS-dda31474-047f-4e68-a9d3-258c88e6eb6e:NORMAL:127.0.0.1:48963|FINALIZED]]} size 0 [junit4] 2> 422726 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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:38992/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29da46d4; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 422726 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 422731 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 422731 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 422731 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 422731 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 422732 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 422732 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422732 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422732 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422732 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422732 WARN (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422733 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 422735 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 422736 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 422738 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 422744 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.RequestHandlers Registered paths: /admin/mbeans,standard,/dump,/update/csv,/sql,/update/json/docs,/admin/luke,/export,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/stream,/schema,/admin/plugins,/admin/logging,/update/json,/admin/ping,/admin/threads,/update,/admin/file [junit4] 2> 422744 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422744 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:51459_ho_urd 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:38992/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 422749 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422749 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422749 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=2 [junit4] 2> 422768 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 422768 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 422769 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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=2, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=67.2841796875, floorSegmentMB=1.5380859375, forceMergeDeletesPctAllowed=21.61425261517323, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 422778 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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:38992/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@29da46d4; maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 422778 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 422778 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5f5c842c[collection1] main] [junit4] 2> 422778 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422780 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422780 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422780 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 422780 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 422780 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422781 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422781 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422781 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 422781 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 422782 INFO (searcherExecutor-1341-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5f5c842c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 422782 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422782 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422782 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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> 422782 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd 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 1534996614672809984 [junit4] 2> 422785 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 2.0ms to seed version buckets with highest version 1534996614672809984 [junit4] 2> 422785 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 422786 INFO (coreLoadExecutor-1340-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 422788 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd 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:51459/ho_urd collection:control_collection shard:shard1 [junit4] 2> 422788 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd 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> 422793 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd 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/95937288312913924-core_node1-n_0000000000 [junit4] 2> 422793 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd 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> 422795 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 422795 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd 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> 422795 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:51459/ho_urd/collection1/ [junit4] 2> 422796 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 422796 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:51459/ho_urd/collection1/ has no replicas [junit4] 2> 422795 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] 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> 422797 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 422798 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd 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/95937288312913924-core_node1-n_0000000000 [junit4] 2> 422799 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] 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:51459/ho_urd";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 422800 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd 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:51459/ho_urd/collection1/ shard1 [junit4] 2> 422901 INFO (zkCallback-556-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd ] 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> 422951 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:51459/ho_urd/collection1/ and leader is http://127.0.0.1:51459/ho_urd/collection1/ [junit4] 2> 422951 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd 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:51459/ho_urd [junit4] 2> 422951 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 422951 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 422951 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd 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> 422952 INFO (coreZkRegister-1334-thread-1-processing-n:127.0.0.1:51459_ho_urd x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:51459_ho_urd c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (1) [junit4] 2> 422952 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51459/ho_urd";, [junit4] 2> "node_name":"127.0.0.1:51459_ho_urd", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 422953 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51459/ho_urd";, [junit4] 2> "node_name":"127.0.0.1:51459_ho_urd", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 422983 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 422983 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 423001 INFO (zkCallback-558-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@12dd06d1 name:ZooKeeperConnection Watcher:127.0.0.1:42291/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 423001 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 423002 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 423002 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 423003 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 423003 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 423003 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 423003 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 423013 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 423014 INFO (zkCallback-559-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1e979170 name:ZooKeeperConnection Watcher:127.0.0.1:42291/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 423015 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 423015 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 423016 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.Overseer processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"2", [junit4] 2> "stateFormat":"2"} current state version: 2 [junit4] 2> 423016 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.o.ClusterStateMutator building a new cName: collection1 [junit4] 2> 423017 INFO (zkCallback-558-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> 423017 INFO (zkCallback-556-thread-1-processing-n:127.0.0.1:51459_ho_urd) [n:127.0.0.1:51459_ho_urd ] 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> 423094 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/shard-1-001/cores/collection1 [junit4] 2> 423095 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/shard-1-001 [junit4] 2> 423096 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.e.j.s.Server jetty-9.3.8.v20160314 [junit4] 2> 423113 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4cf4c18e{/ho_urd,null,AVAILABLE} [junit4] 2> 423114 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.e.j.s.ServerConnector Started ServerConnector@18c3de3b{HTTP/1.1,[http/1.1]}{127.0.0.1:40269} [junit4] 2> 423114 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.e.j.s.Server Started @425148ms [junit4] 2> 423114 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:38992/hdfs__localhost_38992__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-6.0_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/ho_urd, hostPort=40269, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/shard-1-001/cores} [junit4] 2> 423114 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@73d16e93 [junit4] 2> 423114 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/shard-1-001' [junit4] 2> 423115 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 423115 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 423115 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 423116 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 423118 INFO (zkCallback-560-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3ae4f099 name:ZooKeeperConnection Watcher:127.0.0.1:42291/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 423118 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 423118 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 423119 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 423119 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/shard-1-001/solr.xml [junit4] 2> 423122 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/shard-1-001/cores [junit4] 2> 423123 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.CoreContainer New CoreContainer 1058236940 [junit4] 2> 423123 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/shard-1-001] [junit4] 2> 423123 WARN (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsCollectionsAPIDistributedZkTest_21D3B069732F15C5-001/shard-1-001/lib [junit4] 2> 423124 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] 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> 423124 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 423125 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 423125 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 423125 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42291/solr [junit4] 2> 423125 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 423125 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 423125 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 423126 INFO (OverseerStateUpdate-95937288312913924-127.0.0.1:51459_ho_urd-n_0000000000) [n:127.0.0.1:51459_ho_urd ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/collection1/state.json [junit4] 2> 423135 INFO (zkCallback-563-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4ec3fbe4 name:ZooKeeperConnection Watcher:127.0.0.1:42291 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 423136 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 423136 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 423137 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:40269_ho_urd ] o.a.s.c.ZkController Added new OnReconnect listener org.apache.solr.cloud.ZkController$$Lambda$86/693312754@1d6d947c [junit4] 2> 423138 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:40269_ho_urd ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 423139 INFO (zkCallback-564-thread-1-processing-n:127.0.0.1:40269_ho_urd) [n:127.0.0.1:40269_ho_urd ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@4b2833db name:ZooKeeperConnection Watcher:127.0.0.1:42291/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 423139 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:40269_ho_urd ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 423143 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:40269_ho_urd ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 423144 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:40269_ho_urd ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 423145 INFO (TEST-HdfsCollectionsAPIDistributedZkTest.test-seed#[21D3B069732F15C5]) [n:127.0.0.1:40269_ho_urd ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 423145 INFO (TEST-HdfsCollectionsAPIDis [...truncated too long message...] [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:997)Throwable #3: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=4636, name=collection5, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38813/fw_w/g: collection already exists: awholynewstresscollection_collection5_1 [junit4] > at __randomizedtesting.SeedInfo.seed([21D3B069732F15C5]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1595) [junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createCollection(AbstractFullDistribZkTestBase.java:1616) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:990)Throwable #4: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=4634, name=collection3, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38813/fw_w/g: Could not find collection : awholynewstresscollection_collection3_0 [junit4] > at __randomizedtesting.SeedInfo.seed([21D3B069732F15C5]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:997)Throwable #5: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=4631, name=collection0, state=RUNNABLE, group=TGRP-CollectionsAPIDistributedZkTest] [junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:38813/fw_w/g: Could not find collection : awholynewstresscollection_collection0_0 [junit4] > at __randomizedtesting.SeedInfo.seed([21D3B069732F15C5]:0) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:577) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:241) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:230) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:372) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1100) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:870) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:806) [junit4] > at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) [junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest$1CollectionThread.run(CollectionsAPIDistributedZkTest.java:997) [junit4] 2> 1279007 INFO (SUITE-CollectionsAPIDistributedZkTest-seed#[21D3B069732F15C5]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> May 22, 2016 3:28:23 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene60): {other_tl1=PostingsFormat(name=MockRandom), range_facet_l_dv=Lucene50(blocksize=128), _version_=PostingsFormat(name=MockRandom), multiDefault=PostingsFormat(name=Asserting), a_t=PostingsFormat(name=Asserting), intDefault=PostingsFormat(name=MockRandom), a_i1=PostingsFormat(name=Asserting), id=Lucene50(blocksize=128), range_facet_i_dv=PostingsFormat(name=Asserting), text=PostingsFormat(name=LuceneVarGapDocFreqInterval), range_facet_l=PostingsFormat(name=Asserting), timestamp=PostingsFormat(name=Asserting)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, maxPointsInLeafNode=1619, maxMBSortInHeap=6.334947260759888, sim=ClassicSimilarity, locale=et-EE, timezone=Zulu [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_74 (64-bit)/cpus=4,threads=1,free=175228528,total=518520832 [junit4] 2> NOTE: All tests run in this JVM: [TestOverriddenPrefixQueryForCustomFieldType, TestClusterStateMutator, TestFilteredDocIdSet, SpatialFilterTest, TestCollationFieldDocValues, TestHashPartitioner, RecoveryAfterSoftCommitTest, TestLFUCache, TestFreeTextSuggestions, TestWordDelimiterFilterFactory, SortByFunctionTest, TestSimpleTrackingShardHandler, ConfigSetsAPITest, CdcrUpdateLogTest, TestChildDocTransformer, PreAnalyzedUpdateProcessorTest, TestQueryTypes, TestSha256AuthenticationProvider, TestCloudInspectUtil, TestConfigSetImmutable, ZkCLITest, ChangedSchemaMergeTest, OpenExchangeRatesOrgProviderTest, TestSolrCLIRunExample, TestCodecSupport, OpenCloseCoreStressTest, TestSurroundQueryParser, TestBinaryResponseWriter, TestSolrQueryParserDefaultOperatorResource, DistanceUnitsTest, DocExpirationUpdateProcessorFactoryTest, TestAuthorizationFramework, EnumFieldTest, ShardRoutingTest, TestSolrDeletionPolicy2, SharedFSAutoReplicaFailoverUtilsTest, DistributedMLTComponentTest, TestUpdate, CoreMergeIndexesAdminHandlerTest, TestAddFieldRealTimeGet, AddSchemaFieldsUpdateProcessorFactoryTest, TestTrackingShardHandlerFactory, SchemaVersionSpecificBehaviorTest, MergeStrategyTest, ConvertedLegacyTest, HdfsRecoverLeaseTest, DocValuesTest, TestDistribIDF, DistributedQueueTest, TestSchemaSimilarityResource, NumericFieldsTest, TestAnalyzeInfixSuggestions, SolrIndexConfigTest, BlockJoinFacetRandomTest, CollectionsAPIDistributedZkTest] [junit4] Completed [138/584 (2!)] on J1 in 282.88s, 1 test, 1 error <<< FAILURES! [...truncated 1421 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/solr/common-build.xml:527: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.0/lucene/common-build.xml:1014: There were test failures: 584 suites (10 ignored), 2340 tests, 2 errors, 31 ignored (16 assumptions) [seed: 21D3B069732F15C5] Total time: 176 minutes 25 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]
