Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-trunk/932/
3 tests failed. FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test Error Message: Error from server at http://127.0.0.1:57684/awholynewcollection_0: non ok status: 500, message:Server Error Stack Trace: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:57684/awholynewcollection_0: non ok status: 500, message:Server Error at __randomizedtesting.SeedInfo.seed([4FAA39F1B10B8ADB:C7FE062B1FF7E723]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:512) 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.SolrRequest.process(SolrRequest.java:149) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:942) at org.apache.solr.client.solrj.SolrClient.query(SolrClient.java:957) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.waitForNon403or404or503(AbstractFullDistribZkTestBase.java:1773) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.testCollectionsAPI(CollectionsAPIDistributedZkTest.java:743) at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:160) 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:497) 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:964) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939) 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) FAILED: org.apache.solr.cloud.SyncSliceTest.test Error Message: expected:<5> but was:<4> Stack Trace: java.lang.AssertionError: expected:<5> but was:<4> at __randomizedtesting.SeedInfo.seed([4FAA39F1B10B8ADB:C7FE062B1FF7E723]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.failNotEquals(Assert.java:647) at org.junit.Assert.assertEquals(Assert.java:128) at org.junit.Assert.assertEquals(Assert.java:472) at org.junit.Assert.assertEquals(Assert.java:456) at org.apache.solr.cloud.SyncSliceTest.test(SyncSliceTest.java:153) 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:497) 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:964) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939) 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) FAILED: org.apache.solr.cloud.hdfs.HdfsSyncSliceTest.test Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:56414/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:56414/collection1 at __randomizedtesting.SeedInfo.seed([4FAA39F1B10B8ADB:C7FE062B1FF7E723]:0) 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.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:1099) 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.SolrRequest.process(SolrRequest.java:149) at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:484) at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:463) at org.apache.solr.cloud.AbstractFullDistribZkTestBase.commit(AbstractFullDistribZkTestBase.java:1524) at org.apache.solr.cloud.SyncSliceTest.test(SyncSliceTest.java:185) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) 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:964) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939) 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) ... 53 more Build Log: [...truncated 11490 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsSyncSliceTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/init-core-data-001 [junit4] 2> 1410896 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) [junit4] 2> 1410897 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: / [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 1410956 WARN (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 1410967 WARN (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 1410968 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 1410990 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-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_50023_hdfs____ek1uow/webapp [junit4] 2> 1411102 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 1411403 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:50023 [junit4] 2> 1411520 WARN (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 1411522 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 1411545 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-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_47213_datanode____.j0mcrs/webapp [junit4] 2> 1411651 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 1411901 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:47213 [junit4] 2> 1411978 WARN (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 1411979 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 1411999 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-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_40837_datanode____bhh8qv/webapp [junit4] 2> 1412065 INFO (IPC Server handler 3 on 48674) [ ] BlockStateChange BLOCK* processReport: from storage DS-346b379d-17b0-4452-aeb3-68ef63929d49 node DatanodeRegistration(127.0.0.1, datanodeUuid=8b5936de-0133-4a71-89e5-cbb6f69d7495, infoPort=47213, ipcPort=48979, storageInfo=lv=-56;cid=testClusterID;nsid=1332000857;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 1412066 INFO (IPC Server handler 3 on 48674) [ ] BlockStateChange BLOCK* processReport: from storage DS-363382e5-6143-484e-bc3b-82b9eae8d958 node DatanodeRegistration(127.0.0.1, datanodeUuid=8b5936de-0133-4a71-89e5-cbb6f69d7495, infoPort=47213, ipcPort=48979, storageInfo=lv=-56;cid=testClusterID;nsid=1332000857;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 1412127 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.m.log NO JSP Support for /, did not find org.apache.jasper.servlet.JspServlet [junit4] 2> 1412422 INFO (SUITE-HdfsSyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:40837 [junit4] 2> 1412527 INFO (IPC Server handler 2 on 48674) [ ] BlockStateChange BLOCK* processReport: from storage DS-32c13ef7-d56a-4a18-b4e6-12e03112ece8 node DatanodeRegistration(127.0.0.1, datanodeUuid=67d5a7b5-a5bf-472a-baa4-53598869ca3b, infoPort=40837, ipcPort=46223, storageInfo=lv=-56;cid=testClusterID;nsid=1332000857;c=0), blocks: 0, hasStaleStorages: true, processing time: 0 msecs [junit4] 2> 1412528 INFO (IPC Server handler 2 on 48674) [ ] BlockStateChange BLOCK* processReport: from storage DS-b5e0e570-0dbc-4e24-bf36-95d9d879d283 node DatanodeRegistration(127.0.0.1, datanodeUuid=67d5a7b5-a5bf-472a-baa4-53598869ca3b, infoPort=40837, ipcPort=46223, storageInfo=lv=-56;cid=testClusterID;nsid=1332000857;c=0), blocks: 0, hasStaleStorages: false, processing time: 0 msecs [junit4] 2> 1412643 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 1412644 INFO (Thread-7417) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 1412644 INFO (Thread-7417) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 1412744 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.ZkTestServer start zk server on port:59259 [junit4] 2> 1412744 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1412745 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1412748 INFO (zkCallback-1753-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@5640972d name:ZooKeeperConnection Watcher:127.0.0.1:59259 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1412748 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1412748 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1412748 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /solr [junit4] 2> 1412752 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1412753 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1412754 INFO (zkCallback-1754-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@2210a76b name:ZooKeeperConnection Watcher:127.0.0.1:59259/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1412754 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1412754 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1412754 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1 [junit4] 2> 1412757 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/collection1/shards [junit4] 2> 1412758 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection [junit4] 2> 1412760 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/shards [junit4] 2> 1412761 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 1412761 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.xml [junit4] 2> 1412764 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 1412764 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/schema.xml [junit4] 2> 1412767 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1412767 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 1412769 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 1412769 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/stopwords.txt [junit4] 2> 1412771 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 1412771 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/protwords.txt [junit4] 2> 1412774 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 1412774 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/currency.xml [junit4] 2> 1412776 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 1412776 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 1412778 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 1412778 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 1412780 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1412780 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 1412782 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 1412783 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 1412785 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 1412785 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient makePath: /configs/conf1/synonyms.txt [junit4] 2> 1412877 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/cores/collection1 [junit4] 2> 1412878 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1412880 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@761d57bf{/,null,AVAILABLE} [junit4] 2> 1412881 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@65824c91{HTTP/1.1}{127.0.0.1:39573} [junit4] 2> 1412881 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.Server Started @1416687ms [junit4] 2> 1412881 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:48674/hdfs__localhost_48674__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001_tempDir-002_control_data, hostContext=/, hostPort=39573, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/cores} [junit4] 2> 1412881 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1412881 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001' [junit4] 2> 1412881 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1412881 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1412882 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1412885 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1412887 INFO (zkCallback-1755-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@679b79f1 name:ZooKeeperConnection Watcher:127.0.0.1:59259/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1412887 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1412887 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1412889 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1412889 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/solr.xml [junit4] 2> 1412897 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/cores [junit4] 2> 1412897 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.CoreContainer New CoreContainer 1850298749 [junit4] 2> 1412897 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001] [junit4] 2> 1412898 WARN (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/lib [junit4] 2> 1412899 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] 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> 1412901 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1412902 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1412902 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1412902 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59259/solr [junit4] 2> 1412902 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1412903 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1412903 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1412905 INFO (zkCallback-1758-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@162bb55f name:ZooKeeperConnection Watcher:127.0.0.1:59259 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1412905 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1412905 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1412908 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1412910 INFO (zkCallback-1759-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@186a5993 name:ZooKeeperConnection Watcher:127.0.0.1:59259/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1412910 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1412911 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue [junit4] 2> 1412913 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-queue-work [junit4] 2> 1412918 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-running [junit4] 2> 1412920 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-completed [junit4] 2> 1412922 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/collection-map-failure [junit4] 2> 1412924 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes [junit4] 2> 1412927 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /aliases.json [junit4] 2> 1412928 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /clusterstate.json [junit4] 2> 1412930 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /security.json [junit4] 2> 1412931 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39573_ [junit4] 2> 1412932 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /live_nodes/127.0.0.1:39573_ [junit4] 2> 1412933 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect [junit4] 2> 1412934 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/election [junit4] 2> 1412935 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 1412935 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.LeaderElector Joined leadership election with path: /overseer_elect/election/95375573712044036-127.0.0.1:39573_-n_0000000000 [junit4] 2> 1412935 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:39573_ [junit4] 2> 1412935 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /overseer_elect/leader [junit4] 2> 1412939 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.Overseer Overseer (id=95375573712044036-127.0.0.1:39573_-n_0000000000) starting [junit4] 2> 1412942 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.SolrZkClient makePath: /overseer/queue-work [junit4] 2> 1412953 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.OverseerAutoReplicaFailoverThread Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 1412954 INFO (OverseerCollectionConfigSetProcessor-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] o.a.s.c.OverseerTaskProcessor Process current queue of overseer operations [junit4] 2> 1412954 INFO (OverseerStateUpdate-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] o.a.s.c.Overseer Starting to work on the main queue [junit4] 2> 1412954 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1412983 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.CoreContainer Security conf doesn't exist. Skipping setup for authorization module. [junit4] 2> 1412983 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.CoreContainer No authentication plugin used. [junit4] 2> 1412984 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.CorePropertiesLocator Looking for core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/cores [junit4] 2> 1412984 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] 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> 1412984 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.CorePropertiesLocator Found core collection1 in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/cores/collection1 [junit4] 2> 1412985 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions [junit4] 2> 1412997 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.ZkController publishing state=down [junit4] 2> 1412997 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.ZkController numShards not found on descriptor - reading it from system property [junit4] 2> 1413004 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.s.SolrDispatchFilter user.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1413004 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:39573_ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init() done [junit4] 2> 1413005 INFO (OverseerStateUpdate-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] 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:39573";, [junit4] 2> "node_name":"127.0.0.1:39573_", [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> 1413006 INFO (OverseerStateUpdate-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] 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:39573";, [junit4] 2> "node_name":"127.0.0.1:39573_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1413006 INFO (OverseerStateUpdate-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] o.a.s.c.o.ClusterStateMutator building a new cName: control_collection [junit4] 2> 1413007 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.ZkController look for our core node name [junit4] 2> 1413008 INFO (OverseerStateUpdate-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 1413012 INFO (zkCallback-1759-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ ] 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> 1414009 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.ZkController waiting to find shard id in clusterstate for collection1 [junit4] 2> 1414009 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.ZkController Check for collection zkNode:control_collection [junit4] 2> 1414010 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.ZkController Collection zkNode exists [junit4] 2> 1414010 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader Load collection config from: [/collections/control_collection] [junit4] 2> 1414010 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.c.ZkStateReader path=[/collections/control_collection] [configName]=[conf1] specified config exists in ZooKeeper [junit4] 2> 1414010 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/cores/collection1' [junit4] 2> 1414011 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1414011 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1414012 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.Config loaded config solrconfig.xml with version 0 [junit4] 2> 1414017 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.SolrConfig current version of requestparams : -1 [junit4] 2> 1414021 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.0.0 [junit4] 2> 1414029 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.SolrConfig Loaded SolrConfig: solrconfig.xml [junit4] 2> 1414034 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 1414147 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 1414156 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.s.IndexSchema default search field in schema is text [junit4] 2> 1414157 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.s.IndexSchema unique key field: id [junit4] 2> 1414166 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1414168 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.s.FileExchangeRateProvider Reloading exchange rates from file currency.xml [junit4] 2> 1414171 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1414172 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.s.OpenExchangeRatesOrgProvider Reloading exchange rates from open-exchange-rates.json [junit4] 2> 1414173 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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-trunk/solr/build/solr-core/test/J2 [junit4] 2> 1414174 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 1414174 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore org.apache.solr.core.HdfsDirectoryFactory [junit4] 2> 1414174 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:48674/solr_hdfs_home [junit4] 2> 1414174 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 1414174 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/control-001/cores/collection1], dataDir=[hdfs://localhost:48674/solr_hdfs_home/control_collection/core_node1/data/] [junit4] 2> 1414174 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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@7c39c97f [junit4] 2> 1414175 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:48674/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 1414189 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:48674/solr_hdfs_home/control_collection/core_node1/data [junit4] 2> 1414191 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore New index directory detected: old=null new=hdfs://localhost:48674/solr_hdfs_home/control_collection/core_node1/data/index/ [junit4] 2> 1414201 WARN (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Solr index directory 'hdfs:/localhost:48674/solr_hdfs_home/control_collection/core_node1/data/index' doesn't exist. Creating new index... [junit4] 2> 1414201 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:48674/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 1414210 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 1414210 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [8388608] will allocate [1] slabs and use ~[8388608] bytes [junit4] 2> 1414210 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.HdfsDirectoryFactory Creating new single instance HDFS BlockCache [junit4] 2> 1414219 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 1414219 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CachingDirectoryFactory return new directory for hdfs://localhost:48674/solr_hdfs_home/control_collection/core_node1/data/index [junit4] 2> 1414219 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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=10, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.39640577603446314] [junit4] 2> 1414252 INFO (IPC Server handler 2 on 48674) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59640 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-363382e5-6143-484e-bc3b-82b9eae8d958:NORMAL:127.0.0.1:44180|RBW], ReplicaUnderConstruction[[DISK]DS-32c13ef7-d56a-4a18-b4e6-12e03112ece8:NORMAL:127.0.0.1:59640|FINALIZED]]} size 0 [junit4] 2> 1414254 INFO (IPC Server handler 3 on 48674) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:44180 is added to blk_1073741825_1001{blockUCState=UNDER_CONSTRUCTION, primaryNodeIndex=-1, replicas=[ReplicaUnderConstruction[[DISK]DS-363382e5-6143-484e-bc3b-82b9eae8d958:NORMAL:127.0.0.1:44180|RBW], ReplicaUnderConstruction[[DISK]DS-32c13ef7-d56a-4a18-b4e6-12e03112ece8:NORMAL:127.0.0.1:59640|FINALIZED]]} size 0 [junit4] 2> 1414258 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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(BlockDirectory(HdfsDirectory@hdfs://localhost:48674/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49b782c); maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 1414258 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1414261 INFO (OldIndexDirectoryCleanupThreadForCore-collection1) [n:127.0.0.1:39573_ 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:48674/solr_hdfs_home/control_collection/core_node1/data/ [junit4] 2> 1414264 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "nodistrib" [junit4] 2> 1414264 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "dedupe" [junit4] 2> 1414264 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 1414265 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain creating updateRequestProcessorChain "stored_sig" [junit4] 2> 1414265 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.p.UpdateRequestProcessorChain inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 1414265 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1414265 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1414265 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1414265 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1414265 WARN (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1414267 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1414269 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1414270 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1414270 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.l.XMLLoader xsltCacheLifetimeSeconds=60 [junit4] 2> 1414276 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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/threads,/admin/ping,/update,/admin/file [junit4] 2> 1414276 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1414284 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1414284 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 tlogDfsReplication=2 [junit4] 2> 1415216 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 1415216 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 1415217 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5416472763955665] [junit4] 2> 1415225 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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(BlockDirectory(HdfsDirectory@hdfs://localhost:48674/solr_hdfs_home/control_collection/core_node1/data/index lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@49b782c); maxCacheMB=192.0 maxMergeSizeMB=16.0),segFN=segments_1,generation=1} [junit4] 2> 1415225 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrDeletionPolicy newest commit generation = 1 [junit4] 2> 1415225 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d1e0458[collection1] main] [junit4] 2> 1415226 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1415228 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1415228 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1415228 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 1415229 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing RestManager with initArgs: {} [junit4] 2> 1415229 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1415230 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1415231 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1415231 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.r.RestManager Initializing 0 registered ManagedResources [junit4] 2> 1415231 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 1415233 INFO (searcherExecutor-3214-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6d1e0458[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 1415233 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1415233 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.VersionInfo Refreshing highest value of _version_ for 256 version buckets from index [junit4] 2> 1415233 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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> 1415233 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ 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 1526009182105370624 [junit4] 2> 1415233 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.u.UpdateLog Took 0.0ms to seed version buckets with highest version 1526009182105370624 [junit4] 2> 1415233 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController watch zkdir /configs/conf1 [junit4] 2> 1415234 INFO (coreLoadExecutor-3213-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.CoreContainer registering core: collection1 [junit4] 2> 1415236 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ 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:39573 collection:control_collection shard:shard1 [junit4] 2> 1415237 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ 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> 1415242 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ 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/95375573712044036-core_node1-n_0000000000 [junit4] 2> 1415243 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ 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=10000 [junit4] 2> 1415246 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 1415246 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ 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> 1415246 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:39573/collection1/ [junit4] 2> 1415246 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 1415246 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy http://127.0.0.1:39573/collection1/ has no replicas [junit4] 2> 1415246 INFO (OverseerStateUpdate-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] 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> 1415247 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.c.SolrZkClient makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 1415247 INFO (zkCallback-1759-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ ] 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> 1415252 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node [junit4] 2> 1415254 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ 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:39573/collection1/ shard1 [junit4] 2> 1415255 INFO (OverseerStateUpdate-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] 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:39573";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 1415358 INFO (zkCallback-1759-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ ] 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> 1415406 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController We are http://127.0.0.1:39573/collection1/ and leader is http://127.0.0.1:39573/collection1/ [junit4] 2> 1415406 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ 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:39573 [junit4] 2> 1415406 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 1415406 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController publishing state=active [junit4] 2> 1415406 INFO (coreZkRegister-3207-thread-1-processing-n:127.0.0.1:39573_ x:collection1 s:shard1 c:control_collection r:core_node1) [n:127.0.0.1:39573_ 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> 1415408 INFO (OverseerStateUpdate-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] 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:39573";, [junit4] 2> "node_name":"127.0.0.1:39573_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 1415408 INFO (OverseerStateUpdate-95375573712044036-127.0.0.1:39573_-n_0000000000) [n:127.0.0.1:39573_ ] 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:39573";, [junit4] 2> "node_name":"127.0.0.1:39573_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 1415510 INFO (zkCallback-1759-thread-1-processing-n:127.0.0.1:39573_) [n:127.0.0.1:39573_ ] 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> 1415699 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1415700 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1415701 INFO (zkCallback-1761-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@178d79f5 name:ZooKeeperConnection Watcher:127.0.0.1:59259/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1415702 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1415702 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1415702 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ZkStateReader Updating cluster state from ZooKeeper... [junit4] 2> 1415706 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 1415803 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/shard-1-001/cores/collection1 [junit4] 2> 1415804 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/shard-1-001 [junit4] 2> 1415805 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.Server jetty-9.2.13.v20150730 [junit4] 2> 1415816 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5e111f50{/,null,AVAILABLE} [junit4] 2> 1415817 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.ServerConnector Started ServerConnector@19f346f7{HTTP/1.1}{127.0.0.1:58837} [junit4] 2> 1415817 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.Server Started @1419623ms [junit4] 2> 1415817 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=hdfs://localhost:48674/hdfs__localhost_48674__x1_jenkins_jenkins-slave_workspace_Lucene-Solr-NightlyTests-trunk_solr_build_solr-core_test_J2_temp_solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001_tempDir-002_jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=58837, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/shard-1-001/cores} [junit4] 2> 1415817 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.s.SolrDispatchFilter SolrDispatchFilter.init(): sun.misc.Launcher$AppClassLoader@4e0e2f2a [junit4] 2> 1415817 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.SolrResourceLoader new SolrResourceLoader for directory: '/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/shard-1-001' [junit4] 2> 1415817 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.SolrResourceLoader JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 1415817 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI) [junit4] 2> 1415818 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1415819 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1415821 INFO (zkCallback-1762-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@103c09ba name:ZooKeeperConnection Watcher:127.0.0.1:59259/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1415821 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1415822 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1415824 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 1415824 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/shard-1-001/solr.xml [junit4] 2> 1415829 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.CorePropertiesLocator Config-defined core root directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/shard-1-001/cores [junit4] 2> 1415830 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.CoreContainer New CoreContainer 856177768 [junit4] 2> 1415830 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.CoreContainer Loading cores into CoreContainer [instanceDir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/shard-1-001] [junit4] 2> 1415830 WARN (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.CoreContainer Couldn't add files from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/shard-1-001/lib to classpath: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsSyncSliceTest_4FAA39F1B10B8ADB-001/shard-1-001/lib [junit4] 2> 1415830 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] 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> 1415831 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 1415832 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.l.LogWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 1415832 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.l.LogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 1415832 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:59259/solr [junit4] 2> 1415832 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.ZkController zkHost includes chroot [junit4] 2> 1415832 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkCredentialsProvider [junit4] 2> 1415833 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1415834 INFO (zkCallback-1765-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@3e45017d name:ZooKeeperConnection Watcher:127.0.0.1:59259 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1415834 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1415834 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.c.SolrZkClient Using default ZkACLProvider [junit4] 2> 1415836 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:58837_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper [junit4] 2> 1415837 INFO (zkCallback-1766-thread-1-processing-n:127.0.0.1:58837_) [n:127.0.0.1:58837_ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@1537a99a name:ZooKeeperConnection Watcher:127.0.0.1:59259/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 1415837 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0.0.1:58837_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper [junit4] 2> 1415844 INFO (TEST-HdfsSyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [n:127.0 [...truncated too long message...] kins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.SyncSliceTest_4FAA39F1B10B8ADB-001/shard-7-001/cores/collection1/data/index;done=false>>] [junit4] 2> 1889863 INFO (zkCallback-2064-thread-3-processing-n:127.0.0.1:43475_) [n:127.0.0.1:43475_ c:collection1 s:shard1 r:core_node7 x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.SyncSliceTest_4FAA39F1B10B8ADB-001/shard-7-001/cores/collection1/data/index [junit4] 2> 1889870 INFO (zkCallback-2064-thread-3-processing-n:127.0.0.1:43475_) [n:127.0.0.1:43475_ c:collection1 s:shard1 r:core_node7 x:collection1] o.a.s.c.CachingDirectoryFactory looking to close /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.SyncSliceTest_4FAA39F1B10B8ADB-001/shard-7-001/cores/collection1/data [CachedDir<<refCount=0;path=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.SyncSliceTest_4FAA39F1B10B8ADB-001/shard-7-001/cores/collection1/data;done=false>>] [junit4] 2> 1889870 INFO (zkCallback-2064-thread-3-processing-n:127.0.0.1:43475_) [n:127.0.0.1:43475_ c:collection1 s:shard1 r:core_node7 x:collection1] o.a.s.c.CachingDirectoryFactory Closing directory: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.SyncSliceTest_4FAA39F1B10B8ADB-001/shard-7-001/cores/collection1/data [junit4] 2> 1889884 INFO (TEST-SyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.ServerConnector Stopped ServerConnector@728c3408{SSL-http/1.1}{127.0.0.1:0} [junit4] 2> 1889884 INFO (TEST-SyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1a33b8ec{/,null,UNAVAILABLE} [junit4] 2> 1889886 INFO (TEST-SyncSliceTest.test-seed#[4FAA39F1B10B8ADB]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42751 42751 [junit4] 2> 1890066 INFO (Thread-35445) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:42751 42751 [junit4] 2> 1890066 WARN (Thread-35445) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 9 /solr/aliases.json [junit4] 2> 8 /solr/security.json [junit4] 2> 8 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 9 /solr/clusterstate.json [junit4] 2> 2 /solr/overseer_elect/election/95375601725407237-127.0.0.1:55177_-n_0000000000 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 9 /solr/live_nodes [junit4] 2> 9 /solr/collections [junit4] 2> 7 /solr/overseer/queue [junit4] 2> 7 /solr/overseer/collection-queue-work [junit4] 2> [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SyncSliceTest -Dtests.method=test -Dtests.seed=4FAA39F1B10B8ADB -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/x1/jenkins/lucene-data/enwiki.random.lines.txt -Dtests.locale=ar-MA -Dtests.timezone=Europe/Kaliningrad -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 50.0s J2 | SyncSliceTest.test <<< [junit4] > Throwable #1: java.lang.AssertionError: expected:<5> but was:<4> [junit4] > at __randomizedtesting.SeedInfo.seed([4FAA39F1B10B8ADB:C7FE062B1FF7E723]:0) [junit4] > at org.apache.solr.cloud.SyncSliceTest.test(SyncSliceTest.java:153) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:964) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:939) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 1890069 INFO (SUITE-SyncSliceTest-seed#[4FAA39F1B10B8ADB]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build/solr-core/test/J2/temp/solr.cloud.SyncSliceTest_4FAA39F1B10B8ADB-001 [junit4] 2> Feb 12, 2016 10:30:38 PM 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=Lucene50(blocksize=128), range_facet_l_dv=PostingsFormat(name=MockRandom), multiDefault=PostingsFormat(name=Asserting), intDefault=Lucene50(blocksize=128), a_i1=PostingsFormat(name=Asserting), range_facet_l=PostingsFormat(name=Asserting), rnd_b=PostingsFormat(name=MockRandom), _version_=Lucene50(blocksize=128), a_t=PostingsFormat(name=Asserting), id=PostingsFormat(name=MockRandom), range_facet_i_dv=PostingsFormat(name=Asserting), text=BlockTreeOrds(blocksize=128), timestamp=PostingsFormat(name=Asserting)}, docValues:{range_facet_l_dv=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Memory), timestamp=DocValuesFormat(name=Memory)}, sim=RandomSimilarity(queryNorm=true,coord=yes): {}, locale=ar-MA, timezone=Europe/Kaliningrad [junit4] 2> NOTE: Linux 3.13.0-52-generic amd64/Oracle Corporation 1.8.0_66 (64-bit)/cpus=4,threads=1,free=41823464,total=522715136 [junit4] 2> NOTE: All tests run in this JVM: [TestBlobHandler, CircularListTest, DateMathParserTest, TestMacros, SampleTest, BaseCdcrDistributedZkTest, FullSolrCloudDistribCmdsTest, FieldMutatingUpdateProcessorTest, TestRawResponseWriter, RulesTest, TestRandomCollapseQParserPlugin, TestJettySolrRunner, TestSolrDeletionPolicy1, TestSweetSpotSimilarityFactory, OverseerRolesTest, TestSortingResponseWriter, HdfsWriteToMultipleCollectionsTest, TestMiniSolrCloudClusterBase, TestPostingsSolrHighlighter, TestPivotHelperCode, QueryParsingTest, HLLUtilTest, RecoveryAfterSoftCommitTest, TestPerFieldSimilarity, SolrCoreCheckLockOnStartupTest, CollectionStateFormat2Test, RestartWhileUpdatingTest, TestManagedSynonymFilterFactory, TestRestManager, TestNoOpRegenerator, DirectUpdateHandlerOptimizeTest, CleanupOldIndexTest, SliceStateTest, TestConfigSets, TestCharFilters, HighlighterMaxOffsetTest, TestInitQParser, TestDistributedMissingSort, RequestHandlersTest, TestCSVResponseWriter, TestSolrQueryParserResource, BlockCacheTest, AnalysisErrorHandlingTest, DeleteReplicaTest, CursorMarkTest, TestStressReorder, TestDocBasedVersionConstraints, TestLeaderElectionZkExpiry, UUIDUpdateProcessorFallbackTest, TestRequestForwarding, AutoCommitTest, HardAutoCommitTest, TestTolerantSearch, TestSolrCloudWithKerberosAlt, TestSchemaVersionResource, TestInfoStreamLogging, TestSolrJ, PrimitiveFieldTypeTest, StatelessScriptUpdateProcessorFactoryTest, TestIndexSearcher, LeaderElectionTest, SolrXmlInZkTest, TestDynamicFieldResource, SearchHandlerTest, RecoveryZkTest, TestRecoveryHdfs, BigEndianAscendingWordDeserializerTest, TestXIncludeConfig, HdfsBasicDistributedZk2Test, TestStandardQParsers, TestCloudManagedSchemaConcurrent, TestSolrIndexConfig, SecurityConfHandlerTest, TestReload, TestReversedWildcardFilterFactory, AssignTest, SpatialRPTFieldTypeTest, VersionInfoTest, LeaderInitiatedRecoveryOnShardRestartTest, HdfsSyncSliceTest, DebugComponentTest, TestStressRecovery, TestDefaultStatsCache, TestDistribDocBasedVersion, TestUseDocValuesAsStored2, SuggesterWFSTTest, XmlUpdateRequestHandlerTest, CoreAdminHandlerTest, TestOrdValues, TestRecovery, TestFieldSortValues, GraphQueryTest, CloneFieldUpdateProcessorFactoryTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest] [junit4] Completed [255/577 (3!)] on J2 in 50.75s, 1 test, 1 failure <<< FAILURES! [...truncated 1039 lines...] BUILD FAILED /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:747: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:684: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/build.xml:59: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/build.xml:233: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/solr/common-build.xml:524: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1457: The following error occurred while executing this line: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-trunk/lucene/common-build.xml:1014: There were test failures: 577 suites (8 ignored), 2325 tests, 2 errors, 1 failure, 34 ignored (18 assumptions) [seed: 4FAA39F1B10B8ADB] Total time: 206 minutes 39 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]
