Build: https://builds.apache.org/job/Lucene-Solr-Tests-5.x-Java7/2785/
4 tests failed. REGRESSION: org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds Error Message: 1: soft wasn't fast enough Stack Trace: java.lang.AssertionError: 1: soft wasn't fast enough at __randomizedtesting.SeedInfo.seed([6ABF00E10BDD88AD:36AAAED8E05FC9D5]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertNotNull(Assert.java:526) at org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:316) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.LeaderInitiatedRecoveryOnCommitTest.test Error Message: IOException occured when talking to server at: http://127.0.0.1:51414/_yao/g/c8n_1x3_commits_shard1_replica3 Stack Trace: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51414/_yao/g/c8n_1x3_commits_shard1_replica3 at __randomizedtesting.SeedInfo.seed([6ABF00E10BDD88AD:E2EB3F3BA521E555]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:598) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:236) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:228) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:483) at org.apache.solr.client.solrj.SolrClient.commit(SolrClient.java:464) at org.apache.solr.cloud.LeaderInitiatedRecoveryOnCommitTest.oneShardTest(LeaderInitiatedRecoveryOnCommitTest.java:130) at org.apache.solr.cloud.LeaderInitiatedRecoveryOnCommitTest.test(LeaderInitiatedRecoveryOnCommitTest.java:62) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) 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:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:490) ... 48 more FAILED: org.apache.solr.cloud.RecoveryAfterSoftCommitTest.test Error Message: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51618/exg/collection1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51618/exg/collection1 at __randomizedtesting.SeedInfo.seed([6ABF00E10BDD88AD:E2EB3F3BA521E555]:0) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:625) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:948) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:839) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:782) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:174) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:139) at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:153) at org.apache.solr.cloud.RecoveryAfterSoftCommitTest.test(RecoveryAfterSoftCommitTest.java:88) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51618/exg/collection1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:598) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:236) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:228) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:622) ... 49 more Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) 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:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:490) ... 54 more FAILED: org.apache.solr.cloud.ReplicationFactorTest.test Error Message: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51527/repfacttest_c8n_1x3_shard1_replica3 Stack Trace: org.apache.solr.client.solrj.SolrServerException: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51527/repfacttest_c8n_1x3_shard1_replica3 at __randomizedtesting.SeedInfo.seed([6ABF00E10BDD88AD:E2EB3F3BA521E555]:0) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:625) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:948) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:839) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:782) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.ReplicationFactorTest.sendDoc(ReplicationFactorTest.java:308) at org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:234) at org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:113) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51527/repfacttest_c8n_1x3_shard1_replica3 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:598) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:236) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:228) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:622) ... 48 more Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) 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:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:490) ... 53 more Build Log: [...truncated 9186 lines...] [junit4] Suite: org.apache.solr.update.SoftAutoCommitTest [junit4] 2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout. [junit4] 2> Creating dataDir: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001 [junit4] 2> 5254 T11 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (false) and clientAuth (false) [junit4] 2> 5447 T11 oas.SolrTestCaseJ4.initCore ####initCore [junit4] 2> 6129 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/' [junit4] 2> 6141 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 6142 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 6143 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 7490 T11 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 7725 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 8140 T11 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 8211 T11 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 8370 T11 oass.IndexSchema.readSchema [null] Schema name=test [junit4] 2> 12236 T11 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 12381 T11 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 12389 T11 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 13971 T11 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 14016 T11 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 14025 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 14036 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 14036 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 14037 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 14039 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 14039 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 14087 T11 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 14088 T11 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr [junit4] 2> 14089 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/' [junit4] 2> 14318 T11 oasc.CoreContainer.<init> New CoreContainer 1095283640 [junit4] 2> 14319 T11 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/] [junit4] 2> 14319 T11 oasc.CoreContainer.load loading shared library: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/lib [junit4] 2> 14321 T11 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/lib). [junit4] 2> 14407 T11 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 600000 [junit4] 2> 14408 T11 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: null [junit4] 2> 14423 T11 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 60000 [junit4] 2> 14424 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 14425 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 14425 T11 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 14426 T11 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 14427 T11 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 14427 T11 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 14428 T11 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 14428 T11 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 15613 T11 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true [junit4] 2> 15652 T11 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 15663 T11 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 15680 T11 oasc.CoreContainer.load Node Name: testNode [junit4] 1> CORE DESCRIPTOR: {collection=collection1, config=solrconfig.xml, schema=schema.xml, shard=shard1, transient=false, absoluteInstDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/, instanceDir=collection1, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001, loadOnStartup=true, name=collection1} [junit4] 2> 16087 T12 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/' [junit4] 2> 16090 T12 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 16091 T12 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 16093 T12 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 16270 T12 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 16311 T12 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 16391 T12 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 16393 T12 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 16470 T12 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 17091 T12 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 17128 T12 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 17133 T12 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 17178 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 17187 T12 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 17197 T12 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 17200 T12 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 17201 T12 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 17203 T12 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 17205 T12 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 17206 T12 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 17214 T12 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from instancedir /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/ [junit4] 2> 17574 T12 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 17611 T12 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/ [junit4] 2> 17620 T12 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX. [junit4] 2> 17826 T12 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001 [junit4] 2> 17828 T12 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/index/ [junit4] 2> 17829 T12 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/index' doesn't exist. Creating new index... [junit4] 2> 17869 T12 oasc.CachingDirectoryFactory.get return new directory for /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/index [junit4] 2> 17873 T12 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.8191633103473063] [junit4] 2> 18307 T12 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_1,generation=1} [junit4] 2> 18315 T12 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 20711 T12 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 20903 T12 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 20914 T12 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 20915 T12 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 20915 T12 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 20916 T12 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 20917 T12 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 20975 T12 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 20975 T12 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 21320 T12 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 21321 T12 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 21322 T12 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 21701 T12 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 21749 T12 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 21752 T12 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 21755 T12 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 22056 T12 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 22160 T12 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/threads,/admin/ping,/search-facet-def,/debug/dump,spellCheckWithWordbreak_Direct,/mlt,mock,tvrh,/config,/search-facet-invariants,/update/json/docs,/admin/logging,/update/json,spellCheckCompRH_Direct,/get,/admin/luke,lazy,/admin/system,/update/csv,/terms,mltrh,/admin/segments,/admin/properties,/admin/file,/update,spellCheckCompRH,/replication,/schema,/admin/plugins,standard,/admin/mbeans,spellCheckWithWordbreak,defaults,dismax,spellCheckCompRH1 [junit4] 2> 22171 T12 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 22174 T12 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 22305 T12 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 22306 T12 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 22360 T12 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 22361 T12 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 22362 T12 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.30622359059849213] [junit4] 2> 22379 T12 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_1,generation=1} [junit4] 2> 22380 T12 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 22621 T12 oass.SolrIndexSearcher.<init> Opening Searcher@22131fde[collection1] main [junit4] 2> 22735 T12 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 22911 T12 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 22912 T12 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 23141 T12 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 23544 T12 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 23970 T12 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 23972 T13 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 23975 T13 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 23975 T13 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 23976 T13 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 23979 T13 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 23981 T12 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 23981 T13 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 23983 T13 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 23985 T13 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 23986 T13 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 23994 T13 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@22131fde[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 24018 T11 oas.SolrTestCaseJ4.initCore ####initCore end [junit4] 2> 24079 T11 oas.SolrTestCaseJ4.setUp ###Starting testSoftAndHardCommitMaxTimeRapidAdds [junit4] 2> 24079 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/' [junit4] 2> 24081 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 24082 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 24083 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 24262 T11 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 24304 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 24383 T11 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 24385 T11 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 24448 T11 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 24896 T11 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 24923 T11 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 24926 T11 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 24952 T11 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 24958 T11 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 24963 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 24964 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 24965 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 24966 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 24966 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 24967 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 24968 T11 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/ [junit4] 2> 24968 T11 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/ [junit4] 2> 24969 T11 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX. [junit4] 2> 24970 T11 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/index/ [junit4] 2> 24973 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 24974 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 24975 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 24975 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 24976 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 24976 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 24977 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 24978 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 24978 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 24979 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 24980 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 24980 T11 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 25006 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 25008 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 25010 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 25012 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 25016 T11 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 25018 T11 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/threads,/admin/ping,/search-facet-def,/debug/dump,spellCheckWithWordbreak_Direct,/mlt,mock,tvrh,/config,/search-facet-invariants,/update/json/docs,/admin/logging,/update/json,spellCheckCompRH_Direct,/get,/admin/luke,lazy,/admin/system,/update/csv,/terms,mltrh,/admin/segments,/admin/properties,/admin/file,/update,spellCheckCompRH,/replication,/schema,/admin/plugins,standard,/admin/mbeans,spellCheckWithWordbreak,defaults,dismax,spellCheckCompRH1 [junit4] 2> 25020 T11 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 25022 T11 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 25029 T11 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 25030 T11 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 25031 T11 oass.SolrIndexSearcher.<init> Opening Searcher@ac746a1[collection1] main [junit4] 2> 25032 T11 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 25032 T11 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 25033 T11 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 25033 T11 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 25036 T11 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 25049 T11 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 25051 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 25051 T11 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter... [junit4] 2> 25052 T11 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 25052 T11 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1 [junit4] 2> 25052 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 25053 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 25054 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 25054 T11 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1370835679, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 25055 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 25056 T11 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_1,generation=1} [junit4] 2> 25057 T11 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 25057 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 25057 T11 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used. [junit4] 2> 25058 T11 oasc.SolrCore.getSearcher WARN [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2 [junit4] 2> 25059 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 25059 T11 oass.SolrIndexSearcher.<init> Opening Searcher@7e54423[collection1] main [junit4] 2> 25060 T11 oasc.CoreContainer.registerCore replacing core: collection1 [junit4] 2> 25060 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 25060 T11 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@15d1d864 [junit4] 2> 25062 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 25062 T11 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=0,transaction_logs_total_number=0} [junit4] 2> 25062 T15 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ac746a1[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 25063 T11 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> 25063 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 25065 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 25065 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 25066 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 25067 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 25068 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 25069 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 25070 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 25072 T15 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 25072 T15 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e54423[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@5f687063 [junit4] 2> 26719 T11 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5000 (1495877742145568768)]} 0 1632 [junit4] 2> 26798 T11 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5001 (1495877742993866752)]} 0 2 [junit4] 2> 26801 T11 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5002 (1495877742998061056)]} 0 1 [junit4] 2> 26805 T11 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5003 (1495877743001206784)]} 0 2 [junit4] 2> 26808 T11 C0 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[5004 (1495877743005401088)]} 0 1 [junit4] 2> 27217 T17 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 27811 T11 oas.SolrTestCaseJ4.tearDown ###Ending testSoftAndHardCommitMaxTimeRapidAdds [junit4] 2> 27909 T16 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SoftAutoCommitTest -Dtests.method=testSoftAndHardCommitMaxTimeRapidAdds -Dtests.seed=6ABF00E10BDD88AD -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=bg_BG -Dtests.timezone=Pacific/Johnston -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] FAILURE 3.94s J3 | SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds <<< [junit4] > Throwable #1: java.lang.AssertionError: 1: soft wasn't fast enough [junit4] > at __randomizedtesting.SeedInfo.seed([6ABF00E10BDD88AD:36AAAED8E05FC9D5]:0) [junit4] > at org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeRapidAdds(SoftAutoCommitTest.java:316) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 27976 T11 oas.SolrTestCaseJ4.setUp ###Starting testSoftAndHardCommitMaxTimeDelete [junit4] 2> 27977 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/' [junit4] 2> 27979 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 27980 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 27981 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 28158 T11 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 28201 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 28262 T17 oass.SolrIndexSearcher.<init> Opening Searcher@735397d4[collection1] main [junit4] 2> 28278 T17 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 28279 T15 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@735397d4[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5)))} [junit4] 2> 28280 T16 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_1,generation=1} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_2,generation=2} [junit4] 2> 28280 T16 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 28281 T16 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 28282 T16 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 28286 T11 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 28288 T11 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 28394 T11 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 28827 T11 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 28855 T11 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 28859 T11 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 28885 T11 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 28891 T11 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 28896 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 28898 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 28898 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 28899 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 28900 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 28900 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 28901 T11 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/ [junit4] 2> 28902 T11 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/ [junit4] 2> 28902 T11 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX. [junit4] 2> 28903 T11 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/index/ [junit4] 2> 28906 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 28907 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 28907 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 28908 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 28908 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 28909 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 28910 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 28911 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 28911 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 28912 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 28913 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 28913 T11 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 28942 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 28945 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 28947 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 28949 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 28953 T11 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 28955 T11 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/threads,/admin/ping,/search-facet-def,/debug/dump,spellCheckWithWordbreak_Direct,/mlt,mock,tvrh,/config,/search-facet-invariants,/update/json/docs,/admin/logging,/update/json,spellCheckCompRH_Direct,/get,/admin/luke,lazy,/admin/system,/update/csv,/terms,mltrh,/admin/segments,/admin/properties,/admin/file,/update,spellCheckCompRH,/replication,/schema,/admin/plugins,standard,/admin/mbeans,spellCheckWithWordbreak,defaults,dismax,spellCheckCompRH1 [junit4] 2> 28964 T11 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 28966 T11 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 28967 T11 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 28968 T11 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 28969 T11 oass.SolrIndexSearcher.<init> Opening Searcher@4dc938e7[collection1] main [junit4] 2> 28970 T11 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 28970 T11 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 28971 T11 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 28971 T11 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 28975 T11 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 28988 T11 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 28989 T18 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 28989 T11 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter... [junit4] 2> 28990 T11 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 28990 T11 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1 [junit4] 2> 28990 T18 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 28991 T18 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 28991 T11 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=24, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 28992 T18 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 28994 T18 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 28996 T18 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 28996 T11 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_2,generation=2} [junit4] 2> 28997 T11 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2 [junit4] 2> 28997 T18 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 28997 T11 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used. [junit4] 2> 28998 T11 oasc.SolrCore.getSearcher WARN [collection1] PERFORMANCE WARNING: Overlapping onDeckSearchers=2 [junit4] 2> 28999 T18 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 29000 T18 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 29000 T18 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4dc938e7[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5)))} [junit4] 2> 29001 T11 oass.SolrIndexSearcher.<init> Opening Searcher@142abb86[collection1] main [junit4] 2> 29003 T11 oasc.CoreContainer.registerCore replacing core: collection1 [junit4] 2> 29003 T18 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@142abb86[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5)))} [junit4] 2> 29003 T11 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5f687063 [junit4] 2> 29005 T11 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=2,autocommit maxTime=1200ms,autocommits=1,soft autocommit maxTime=500ms,soft autocommits=1,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=339,transaction_logs_total_number=1} [junit4] 2> 29006 T11 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@1f29f4e2 [junit4] 2> 29012 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[529 (1495877745312268288)]} 0 5 [junit4] 2> 29015 T11 C1 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 29114 T11 C1 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_2,generation=2} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_3,generation=3} [junit4] 2> 29115 T11 C1 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3 [junit4] 2> 29116 T11 C1 oass.SolrIndexSearcher.<init> Opening Searcher@3c30ed50[collection1] main [junit4] 2> 29117 T11 C1 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 29117 T18 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3c30ed50[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5) Uninverting(_1(5.1.0):c1)))} [junit4] 2> 29118 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {commit=} 0 103 [junit4] 2> 29124 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {delete=[529 (-1495877745431805952)]} 0 3 [junit4] 2> 29625 T20 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 29643 T20 oass.SolrIndexSearcher.<init> Opening Searcher@53c0f47a[collection1] main [junit4] 2> 29645 T20 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 29645 T18 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@53c0f47a[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5)))} [junit4] 2> 29648 T11 C1 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[550 (1495877745981259776)]} 0 3 [junit4] 2> 30148 T20 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 30160 T20 oass.SolrIndexSearcher.<init> Opening Searcher@68109c74[collection1] main [junit4] 2> 30161 T20 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 30161 T18 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@68109c74[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5) Uninverting(_2(5.1.0):c1)))} [junit4] 2> 30327 T19 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 30330 T19 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_3,generation=3} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_4,generation=4} [junit4] 2> 30330 T19 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4 [junit4] 2> 30331 T19 oasc.SolrCore.openNewSearcher SolrIndexSearcher has not changed - not re-opening: org.apache.solr.search.SolrIndexSearcher [junit4] 2> 30332 T19 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 32332 T11 oas.SolrTestCaseJ4.tearDown ###Ending testSoftAndHardCommitMaxTimeDelete [junit4] 2> 32342 T11 oas.SolrTestCaseJ4.setUp ###Starting testSoftAndHardCommitMaxTimeMixedAdds [junit4] 2> 32342 T11 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/' [junit4] 2> 32343 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/classes/' to classloader [junit4] 2> 32344 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/README' to classloader [junit4] 2> 32345 T11 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/lib/.svn/' to classloader [junit4] 2> 32490 T11 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 32528 T11 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 32600 T11 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 32601 T11 oass.IndexSchema.readSchema Reading Solr Schema from /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/conf/schema.xml [junit4] 2> 32642 T11 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 33073 T11 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 33097 T11 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 33100 T11 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 33124 T11 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 33129 T11 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 33134 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 33136 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 33136 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 33137 T11 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 33138 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 33138 T11 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 33138 T11 oasc.CoreContainer.reload Reloading SolrCore 'collection1' using configuration from instancedir /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/ [junit4] 2> 33139 T11 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/core/src/test-files/solr/collection1/, dataDir=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/ [junit4] 2> 33140 T11 oasc.JmxMonitoredMap.<init> No JMX servers found, not exposing Solr information with JMX. [junit4] 2> 33140 T11 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J3/temp/solr.update.SoftAutoCommitTest 6ABF00E10BDD88AD-001/init-core-data-001/index/ [junit4] 2> 33143 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 33144 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 33144 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 33145 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields" [junit4] 2> 33145 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 33146 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 33146 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields" [junit4] 2> 33147 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields" [junit4] 2> 33147 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 33148 T11 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 33149 T11 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 33149 T11 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 33181 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 33184 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 33192 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 33195 T11 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 33199 T11 oasc.PluginBag.createPlugin requestHandler : 'lazy' created with startup=lazy [junit4] 2> 33201 T11 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/threads,/admin/ping,/search-facet-def,/debug/dump,spellCheckWithWordbreak_Direct,/mlt,mock,tvrh,/config,/search-facet-invariants,/update/json/docs,/admin/logging,/update/json,spellCheckCompRH_Direct,/get,/admin/luke,lazy,/admin/system,/update/csv,/terms,mltrh,/admin/segments,/admin/properties,/admin/file,/update,spellCheckCompRH,/replication,/schema,/admin/plugins,standard,/admin/mbeans,spellCheckWithWordbreak,defaults,dismax,spellCheckCompRH1 [junit4] 2> 33203 T11 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler. [junit4] 2> 33204 T11 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 33205 T11 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 33206 T11 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 33207 T11 oass.SolrIndexSearcher.<init> Opening Searcher@59e786fa[collection1] main [junit4] 2> 33208 T11 oasr.RestManager.init Initializing RestManager with initArgs: {storageIO=org.apache.solr.rest.ManagedResourceStorage$InMemoryStorageIO} [junit4] 2> 33209 T11 oasr.ManagedResourceStorage.load Reading _rest_managed.json using InMemoryStorage [junit4] 2> 33209 T11 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 33210 T11 oashc.SpellCheckComponent.inform Initializing spell checkers [junit4] 2> 33213 T11 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 33224 T11 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 33230 T21 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: default [junit4] 2> 33230 T11 oasu.DefaultSolrCoreState.newIndexWriter Creating new IndexWriter... [junit4] 2> 33231 T11 oasu.DefaultSolrCoreState.newIndexWriter Waiting until IndexWriter is unused... core=collection1 [junit4] 2> 33232 T11 oasu.DefaultSolrCoreState.newIndexWriter Closing old IndexWriter... core=collection1 [junit4] 2> 33232 T21 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: direct [junit4] 2> 33232 T21 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: wordbreak [junit4] 2> 33233 T21 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: multipleFields [junit4] 2> 33233 T11 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=36, maxMergeAtOnceExplicit=23, maxMergedSegmentMB=46.5791015625, floorSegmentMB=0.5654296875, forceMergeDeletesPctAllowed=10.675504879480979, segmentsPerTier=49.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.7962975636799338 [junit4] 2> 33234 T21 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: jarowinkler [junit4] 2> 33235 T21 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: external [junit4] 2> 33236 T21 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: freq [junit4] 2> 33237 T21 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: fqcn [junit4] 2> 33238 T11 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_4,generation=4} [junit4] 2> 33238 T11 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4 [junit4] 2> 33238 T21 oashc.SpellCheckComponent$SpellCheckerListener.newSearcher Loading spell index for spellchecker: perDict [junit4] 2> 33239 T11 oasu.DefaultSolrCoreState.newIndexWriter New IndexWriter is ready to be used. [junit4] 2> 33239 T21 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@59e786fa[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5) Uninverting(_2(5.1.0):c1)))} [junit4] 2> 33245 T11 oass.SolrIndexSearcher.<init> Opening Searcher@49afeccc[collection1] main [junit4] 2> 33246 T11 oasc.CoreContainer.registerCore replacing core: collection1 [junit4] 2> 33246 T21 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@49afeccc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5) Uninverting(_2(5.1.0):c1)))} [junit4] 2> 33247 T11 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1f29f4e2 [junit4] 2> 33249 T11 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=4,autocommit maxTime=1200ms,autocommits=1,soft autocommit maxTime=500ms,soft autocommits=2,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=1,cumulative_deletesByQuery=0,cumulative_errors=0,transaction_logs_total_size=633,transaction_logs_total_number=3} [junit4] 2> 33250 T11 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request. [junit4] 2> ASYNC NEW_CORE C2 name=collection1 org.apache.solr.core.SolrCore@13603899 [junit4] 2> 33256 T11 C2 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[529 (1495877749763473408)]} 0 5 [junit4] 2> 33757 T23 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 33779 T23 oass.SolrIndexSearcher.<init> Opening Searcher@33666fbb[collection1] main [junit4] 2> 33780 T23 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 33780 T21 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@33666fbb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5) Uninverting(_2(5.1.0):c1) Uninverting(_3(5.1.0):c1)))} [junit4] 2> 33784 T11 C2 oasup.LogUpdateProcessor.finish [collection1] webapp=null path=null params={} {add=[530 (1495877750318170112)]} 0 4 [junit4] 2> 34285 T23 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=true,prepareCommit=false} [junit4] 2> 34302 T23 oass.SolrIndexSearcher.<init> Opening Searcher@7fc6ab97[collection1] main [junit4] 2> 34303 T23 oasu.DirectUpdateHandler2.commit end_commit_flush [junit4] 2> 34303 T21 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7fc6ab97[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(5.1.0):c5) Uninverting(_2(5.1.0):c1) Uninverting(_3(5.1.0):c1) Uninverting(_4(5.1.0):c1)))} [junit4] 2> 34457 T22 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false} [junit4] 2> 34460 T22 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_4,generation=4} [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@30c145af lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@60c5fd2a),segFN=segments_5,generation=5} [junit4] 2> 34460 T22 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5 [ju [...truncated too long message...] [junit4] 2> 1380401 T3204 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:51593 51593 [junit4] 2> 1380403 T3204 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 4 /solr/clusterstate.json [junit4] 2> 4 /solr/aliases.json [junit4] 2> 3 /solr/configs/conf1 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 4 /solr/live_nodes [junit4] 2> 3 /solr/overseer/collection-queue-work [junit4] 2> 3 /solr/overseer/queue [junit4] 2> [junit4] 2> 1380403 T3203 oasc.SocketProxy.close WARN Closing 0 connections to: http://127.0.0.1:51598/exg [junit4] 2> 1380404 T3203 oasc.SocketProxy.close WARN Closing 5 connections to: http://127.0.0.1:51618/exg [junit4] 2> 1380404 T3203 oasc.SocketProxy.close WARN Closing 0 connections to: http://127.0.0.1:51633/exg [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RecoveryAfterSoftCommitTest -Dtests.method=test -Dtests.seed=6ABF00E10BDD88AD -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=sq -Dtests.timezone=Africa/Accra -Dtests.asserts=true -Dtests.file.encoding=US-ASCII [junit4] ERROR 66.8s J0 | RecoveryAfterSoftCommitTest.test <<< [junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51618/exg/collection1 [junit4] > at __randomizedtesting.SeedInfo.seed([6ABF00E10BDD88AD:E2EB3F3BA521E555]:0) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:625) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:948) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:839) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:782) [junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:135) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:174) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:139) [junit4] > at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:153) [junit4] > at org.apache.solr.cloud.RecoveryAfterSoftCommitTest.test(RecoveryAfterSoftCommitTest.java:88) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958) [junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] > Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51618/exg/collection1 [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:598) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:236) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:228) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370) [junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) [junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:622) [junit4] > ... 49 more [junit4] > Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143) [junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) [junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) [junit4] > at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) [junit4] > at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) [junit4] > at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) [junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) [junit4] > at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) [junit4] > at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) [junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) [junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:490) [junit4] > ... 54 more [junit4] 2> 1380418 T3203 oas.SolrTestCaseJ4.deleteCore ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build/solr-core/test/J0/temp/solr.cloud.RecoveryAfterSoftCommitTest 6ABF00E10BDD88AD-001 [junit4] 2> 66821 T3202 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {timestamp=PostingsFormat(name=LuceneFixedGap), _version_=Lucene50(blocksize=128), text=PostingsFormat(name=Asserting), intDefault=Lucene50(blocksize=128), a_t=PostingsFormat(name=LuceneFixedGap), multiDefault=PostingsFormat(name=LuceneFixedGap), range_facet_l=PostingsFormat(name=LuceneFixedGap), id=PostingsFormat(name=Memory doPackFST= true)}, docValues:{timestamp=DocValuesFormat(name=Lucene50)}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=sq, timezone=Africa/Accra [junit4] 2> NOTE: FreeBSD 9.1-RELEASE-p3 amd64/Oracle Corporation 1.7.0_65 (64-bit)/cpus=16,threads=1,free=119219072,total=333447168 [junit4] 2> NOTE: All tests run in this JVM: [TestIBSimilarityFactory, TestNonDefinedSimilarityFactory, LukeRequestHandlerTest, XsltUpdateRequestHandlerTest, TestCustomSort, TestFieldTypeResource, DeleteInactiveReplicaTest, UUIDFieldTest, HighlighterTest, TestSolrDeletionPolicy2, DocExpirationUpdateProcessorFactoryTest, MoreLikeThisHandlerTest, SharedFSAutoReplicaFailoverUtilsTest, TestJmxMonitoredMap, JSONWriterTest, HdfsUnloadDistributedZkTest, BlockDirectoryTest, SolrIndexConfigTest, FullSolrCloudDistribCmdsTest, HdfsSyncSliceTest, BadComponentTest, TestPseudoReturnFields, TestIntervalFaceting, TestRandomMergePolicy, DirectSolrSpellCheckerTest, MBeansHandlerTest, TestInitQParser, TestCryptoKeys, RollingRestartTest, DistanceFunctionTest, ChangedSchemaMergeTest, DistributedQueryComponentOptimizationTest, TestHdfsUpdateLog, TestExactStatsCache, SolrCoreTest, TestZkChroot, SimplePostToolTest, TestJoin, ResponseLogComponentTest, TestOverriddenPrefixQueryForCustomFieldType, ExternalFileFieldSortTest, TestCoreDiscovery, TestManagedSynonymFilterFactory, TestBinaryResponseWriter, DistanceUnitsTest, CoreAdminRequestStatusTest, TestReRankQParserPlugin, SimpleCollectionCreateDeleteTest, DeleteShardTest, HdfsWriteToMultipleCollectionsTest, TestSolrDynamicMBean, TestDistributedGrouping, SynonymTokenizerTest, TestSchemaNameResource, TestSolr4Spatial2, PeerSyncTest, SyncSliceTest, BasicDistributedZk2Test, TestCloudSchemaless, LeaderInitiatedRecoveryOnCommitTest, RecoveryAfterSoftCommitTest] [junit4] Completed on J0 in 67.62s, 1 test, 1 error <<< FAILURES! [...truncated 676 lines...] BUILD FAILED /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:529: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:477: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/build.xml:61: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/extra-targets.xml:39: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/build.xml:191: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/solr/common-build.xml:509: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:1370: The following error occurred while executing this line: /usr/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-5.x-Java7/lucene/common-build.xml:976: There were test failures: 474 suites, 1889 tests, 3 errors, 1 failure, 62 ignored (22 assumptions) Total time: 50 minutes 50 seconds Build step 'Invoke Ant' marked build as failure Archiving artifacts Sending artifact delta relative to Lucene-Solr-Tests-5.x-Java7 #2431 Archived 5 artifacts Archive block size is 32768 Received 0 blocks and 197043119 bytes Compression is 0.0% Took 38 sec Recording test results Email was triggered for: Failure Sending email for trigger: Failure
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org