Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.5/14/
3 tests failed. FAILED: org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates.test_dv Error Message: java.lang.RuntimeException: Error from server at http://127.0.0.1:47103/solr/test_col: Async exception during distributed update: Error from server at http://127.0.0.1:33765/solr/test_col_shard4_replica1: Server Error request: http://127.0.0.1:33765/solr/test_col_shard4_replica1/update?update.distrib=TOLEADER&distrib.from=http%3A%2F%2F127.0.0.1%3A47103%2Fsolr%2Ftest_col_shard3_replica1%2F&wt=javabin&version=2 Remote error message: Failed synchronous update on shard StdNode: http://127.0.0.1:52302/solr/test_col_shard4_replica2/ update: org.apache.solr.client.solrj.request.UpdateRequest@79379c80 Stack Trace: java.util.concurrent.ExecutionException: java.lang.RuntimeException: Error from server at http://127.0.0.1:47103/solr/test_col: Async exception during distributed update: Error from server at http://127.0.0.1:33765/solr/test_col_shard4_replica1: Server Error request: http://127.0.0.1:33765/solr/test_col_shard4_replica1/update?update.distrib=TOLEADER&distrib.from=http%3A%2F%2F127.0.0.1%3A47103%2Fsolr%2Ftest_col_shard3_replica1%2F&wt=javabin&version=2 Remote error message: Failed synchronous update on shard StdNode: http://127.0.0.1:52302/solr/test_col_shard4_replica2/ update: org.apache.solr.client.solrj.request.UpdateRequest@79379c80 at __randomizedtesting.SeedInfo.seed([4A51F0A245DB66BA:7C4592E4CF865CAB]:0) at java.util.concurrent.FutureTask.report(FutureTask.java:122) at java.util.concurrent.FutureTask.get(FutureTask.java:192) at org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates.checkField(TestStressCloudBlindAtomicUpdates.java:281) at org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates.test_dv(TestStressCloudBlindAtomicUpdates.java:193) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:745) Caused by: java.lang.RuntimeException: Error from server at http://127.0.0.1:47103/solr/test_col: Async exception during distributed update: Error from server at http://127.0.0.1:33765/solr/test_col_shard4_replica1: Server Error request: http://127.0.0.1:33765/solr/test_col_shard4_replica1/update?update.distrib=TOLEADER&distrib.from=http%3A%2F%2F127.0.0.1%3A47103%2Fsolr%2Ftest_col_shard3_replica1%2F&wt=javabin&version=2 Remote error message: Failed synchronous update on shard StdNode: http://127.0.0.1:52302/solr/test_col_shard4_replica2/ update: org.apache.solr.client.solrj.request.UpdateRequest@79379c80 at org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates$Worker.run(TestStressCloudBlindAtomicUpdates.java:409) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:229) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) ... 1 more Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:47103/solr/test_col: Async exception during distributed update: Error from server at http://127.0.0.1:33765/solr/test_col_shard4_replica1: Server Error request: http://127.0.0.1:33765/solr/test_col_shard4_replica1/update?update.distrib=TOLEADER&distrib.from=http%3A%2F%2F127.0.0.1%3A47103%2Fsolr%2Ftest_col_shard3_replica1%2F&wt=javabin&version=2 Remote error message: Failed synchronous update on shard StdNode: http://127.0.0.1:52302/solr/test_col_shard4_replica2/ update: org.apache.solr.client.solrj.request.UpdateRequest@79379c80 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:610) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:160) at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:177) at org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates$Worker.doRandomAtomicUpdate(TestStressCloudBlindAtomicUpdates.java:368) at org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates$Worker.run(TestStressCloudBlindAtomicUpdates.java:397) ... 6 more FAILED: org.apache.solr.cloud.hdfs.HdfsRecoveryZkTest.test Error Message: Address already in use Stack Trace: java.net.BindException: Address already in use at __randomizedtesting.SeedInfo.seed([4A51F0A245DB66BA:C205CF78EB270B42]:0) at sun.nio.ch.Net.bind0(Native Method) at sun.nio.ch.Net.bind(Net.java:433) at sun.nio.ch.Net.bind(Net.java:425) at sun.nio.ch.ServerSocketChannelImpl.bind(ServerSocketChannelImpl.java:223) at sun.nio.ch.ServerSocketAdaptor.bind(ServerSocketAdaptor.java:74) at org.eclipse.jetty.server.ServerConnector.open(ServerConnector.java:317) at org.eclipse.jetty.server.AbstractNetworkConnector.doStart(AbstractNetworkConnector.java:80) at org.eclipse.jetty.server.ServerConnector.doStart(ServerConnector.java:235) at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68) at org.eclipse.jetty.server.Server.doStart(Server.java:401) at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:68) at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:394) at org.apache.solr.client.solrj.embedded.JettySolrRunner.start(JettySolrRunner.java:367) at org.apache.solr.cloud.RecoveryZkTest.test(RecoveryZkTest.java:107) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:745) FAILED: org.apache.solr.cloud.hdfs.StressHdfsTest.test Error Message: Timeout occured while waiting response from server at: http://127.0.0.1:59089 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:59089 at __randomizedtesting.SeedInfo.seed([4A51F0A245DB66BA:C205CF78EB270B42]:0) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:621) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:279) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:268) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:435) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:387) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1376) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:1127) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:1057) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219) at org.apache.solr.cloud.hdfs.StressHdfsTest.createAndDeleteCollection(StressHdfsTest.java:220) at org.apache.solr.cloud.hdfs.StressHdfsTest.test(StressHdfsTest.java:103) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:907) at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:943) at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:957) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:992) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:967) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:916) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:802) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:852) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) at java.lang.Thread.run(Thread.java:745) Caused by: java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:171) at java.net.SocketInputStream.read(SocketInputStream.java:141) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:160) at org.apache.http.impl.io.SocketInputBuffer.fillBuffer(SocketInputBuffer.java:84) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:273) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:140) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:261) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:272) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:124) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:685) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:487) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:882) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:515) ... 51 more Build Log: [...truncated 11820 lines...] [junit4] Suite: org.apache.solr.cloud.hdfs.HdfsRecoveryZkTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_4A51F0A245DB66BA-001/init-core-data-001 [junit4] 2> 391995 WARN (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1 [junit4] 2> 391995 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields [junit4] 2> 391997 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN) [junit4] 2> 392003 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_4A51F0A245DB66BA-001/tempDir-001 [junit4] 2> 392003 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 392004 INFO (Thread-591) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 392004 INFO (Thread-591) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 392104 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:42809 [junit4] 2> 392117 INFO (jetty-launcher-316-thread-1) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 392130 INFO (jetty-launcher-316-thread-2) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 392145 INFO (jetty-launcher-316-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ce3b41f{/solr,null,AVAILABLE} [junit4] 2> 392146 INFO (jetty-launcher-316-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@838581f{/solr,null,AVAILABLE} [junit4] 2> 392152 INFO (jetty-launcher-316-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7cbaf7d{SSL,[ssl, http/1.1]}{127.0.0.1:59108} [junit4] 2> 392152 INFO (jetty-launcher-316-thread-1) [ ] o.e.j.s.Server Started @397692ms [junit4] 2> 392152 INFO (jetty-launcher-316-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=59108} [junit4] 2> 392152 ERROR (jetty-launcher-316-thread-1) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 392153 INFO (jetty-launcher-316-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.1 [junit4] 2> 392153 INFO (jetty-launcher-316-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 392153 INFO (jetty-launcher-316-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 392153 INFO (jetty-launcher-316-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-04-18T09:40:52.570Z [junit4] 2> 392154 INFO (jetty-launcher-316-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1350c73d{SSL,[ssl, http/1.1]}{127.0.0.1:51817} [junit4] 2> 392154 INFO (jetty-launcher-316-thread-2) [ ] o.e.j.s.Server Started @397694ms [junit4] 2> 392154 INFO (jetty-launcher-316-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=51817} [junit4] 2> 392154 ERROR (jetty-launcher-316-thread-2) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 392154 INFO (jetty-launcher-316-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.1 [junit4] 2> 392154 INFO (jetty-launcher-316-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 392154 INFO (jetty-launcher-316-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 392154 INFO (jetty-launcher-316-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-04-18T09:40:52.571Z [junit4] 2> 392163 INFO (jetty-launcher-316-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 392174 INFO (jetty-launcher-316-thread-1) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 392175 INFO (jetty-launcher-316-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42809/solr [junit4] 2> 392178 INFO (jetty-launcher-316-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading... [junit4] 2> 392199 INFO (jetty-launcher-316-thread-2) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 392200 INFO (jetty-launcher-316-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:42809/solr [junit4] 2> 392257 INFO (jetty-launcher-316-thread-2) [n:127.0.0.1:51817_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 392257 INFO (jetty-launcher-316-thread-1) [n:127.0.0.1:59108_solr ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 392258 INFO (jetty-launcher-316-thread-2) [n:127.0.0.1:51817_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:51817_solr [junit4] 2> 392259 INFO (jetty-launcher-316-thread-1) [n:127.0.0.1:59108_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59108_solr [junit4] 2> 392260 INFO (jetty-launcher-316-thread-2) [n:127.0.0.1:51817_solr ] o.a.s.c.Overseer Overseer (id=97813033937928198-127.0.0.1:51817_solr-n_0000000000) starting [junit4] 2> 392263 INFO (zkCallback-324-thread-1-processing-n:127.0.0.1:59108_solr) [n:127.0.0.1:59108_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 392290 INFO (zkCallback-328-thread-1-processing-n:127.0.0.1:51817_solr) [n:127.0.0.1:51817_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 392293 INFO (jetty-launcher-316-thread-2) [n:127.0.0.1:51817_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51817_solr [junit4] 2> 392295 INFO (zkCallback-324-thread-1-processing-n:127.0.0.1:59108_solr) [n:127.0.0.1:59108_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 392295 INFO (zkCallback-328-thread-1-processing-n:127.0.0.1:51817_solr) [n:127.0.0.1:51817_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 392361 INFO (jetty-launcher-316-thread-1) [n:127.0.0.1:59108_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_4A51F0A245DB66BA-001/tempDir-001/node1/. [junit4] 2> 392387 INFO (jetty-launcher-316-thread-2) [n:127.0.0.1:51817_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_4A51F0A245DB66BA-001/tempDir-001/node2/. [junit4] 2> 392631 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 392632 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:42809/solr ready [junit4] 1> Formatting using clusterid: testClusterID [junit4] 2> 392683 WARN (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.h.m.i.MetricsConfig Cannot locate configuration: tried hadoop-metrics2-namenode.properties,hadoop-metrics2.properties [junit4] 2> 392702 WARN (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 392704 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 392721 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/hdfs to ./temp/Jetty_localhost_48860_hdfs____hh93mg/webapp [junit4] 2> 393108 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:48860 [junit4] 2> 393248 WARN (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 393250 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 393268 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_55935_datanode____qac4ki/webapp [junit4] 2> 393696 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:55935 [junit4] 2> 393793 WARN (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.h.h.HttpRequestLog Jetty request log can only be enabled using Log4j [junit4] 2> 393795 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.m.log jetty-6.1.26 [junit4] 2> 393813 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.m.log Extract jar:file:/x1/jenkins/.ivy2/cache/org.apache.hadoop/hadoop-hdfs/tests/hadoop-hdfs-2.7.2-tests.jar!/webapps/datanode to ./temp/Jetty_localhost_47642_datanode____.ajt4g8/webapp [junit4] 2> 393918 INFO (IPC Server handler 3 on 52553) [ ] BlockStateChange BLOCK* processReport: from storage DS-7a3f19cb-7a68-4d9d-8016-95f50db91a2a node DatanodeRegistration(127.0.0.1:59736, datanodeUuid=964bc571-e9ae-46d3-999c-70e18e6691dc, infoPort=51178, infoSecurePort=0, ipcPort=36896, storageInfo=lv=-56;cid=testClusterID;nsid=898923415;c=0), blocks: 0, hasStaleStorage: true, processing time: 0 msecs [junit4] 2> 393918 INFO (IPC Server handler 3 on 52553) [ ] BlockStateChange BLOCK* processReport: from storage DS-6dbd0605-0078-4987-b8b0-9e110b95ad66 node DatanodeRegistration(127.0.0.1:59736, datanodeUuid=964bc571-e9ae-46d3-999c-70e18e6691dc, infoPort=51178, infoSecurePort=0, ipcPort=36896, storageInfo=lv=-56;cid=testClusterID;nsid=898923415;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 394257 INFO (SUITE-HdfsRecoveryZkTest-seed#[4A51F0A245DB66BA]-worker) [ ] o.m.log Started HttpServer2$SelectChannelConnectorWithSafeStartup@localhost:47642 [junit4] 2> 394392 INFO (IPC Server handler 4 on 52553) [ ] BlockStateChange BLOCK* processReport: from storage DS-ebb8b02c-53dd-4ee5-9b34-ef1af77c8d57 node DatanodeRegistration(127.0.0.1:47429, datanodeUuid=063eb7a0-b198-486a-9a75-4e3250497300, infoPort=47414, infoSecurePort=0, ipcPort=33495, storageInfo=lv=-56;cid=testClusterID;nsid=898923415;c=0), blocks: 0, hasStaleStorage: true, processing time: 1 msecs [junit4] 2> 394393 INFO (IPC Server handler 4 on 52553) [ ] BlockStateChange BLOCK* processReport: from storage DS-20190e19-d895-4e50-accd-fa0584d1391c node DatanodeRegistration(127.0.0.1:47429, datanodeUuid=063eb7a0-b198-486a-9a75-4e3250497300, infoPort=47414, infoSecurePort=0, ipcPort=33495, storageInfo=lv=-56;cid=testClusterID;nsid=898923415;c=0), blocks: 0, hasStaleStorage: false, processing time: 0 msecs [junit4] 2> 394458 INFO (TEST-HdfsRecoveryZkTest.test-seed#[4A51F0A245DB66BA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test [junit4] 2> 394584 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=2&collection.configName=conf&maxShardsPerNode=1&name=recoverytest&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true [junit4] 2> 394591 INFO (OverseerThreadFactory-1251-thread-1-processing-n:127.0.0.1:51817_solr) [n:127.0.0.1:51817_solr ] o.a.s.c.CreateCollectionCmd Create collection recoverytest [junit4] 2> 394760 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=recoverytest_shard1_replica2&action=CREATE&numShards=1&collection=recoverytest&shard=shard1&wt=javabin&version=2 [junit4] 2> 394806 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=recoverytest_shard1_replica1&action=CREATE&numShards=1&collection=recoverytest&shard=shard1&wt=javabin&version=2 [junit4] 2> 394911 INFO (zkCallback-324-thread-1-processing-n:127.0.0.1:59108_solr) [n:127.0.0.1:59108_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 394911 INFO (zkCallback-328-thread-1-processing-n:127.0.0.1:51817_solr) [n:127.0.0.1:51817_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 395804 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.1 [junit4] 2> 395821 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.s.IndexSchema [recoverytest_shard1_replica2] Schema name=minimal [junit4] 2> 395821 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.1 [junit4] 2> 395824 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 395826 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.CoreContainer Creating SolrCore 'recoverytest_shard1_replica2' using configuration from collection recoverytest [junit4] 2> 395832 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.s.IndexSchema [recoverytest_shard1_replica1] Schema name=minimal [junit4] 2> 395833 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:52553/data [junit4] 2> 395833 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 395833 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 395834 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SolrCore [[recoverytest_shard1_replica2] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_4A51F0A245DB66BA-001/tempDir-001/node1/recoverytest_shard1_replica2], dataDir=[hdfs://localhost:52553/data/recoverytest/core_node1/data/] [junit4] 2> 395834 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d1c32c2 [junit4] 2> 395837 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id [junit4] 2> 395838 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:52553/data/recoverytest/core_node1/data/snapshot_metadata [junit4] 2> 395838 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.CoreContainer Creating SolrCore 'recoverytest_shard1_replica1' using configuration from collection recoverytest [junit4] 2> 395839 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory solr.hdfs.home=hdfs://localhost:52553/data [junit4] 2> 395839 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory Solr Kerberos Authentication disabled [junit4] 2> 395839 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 395839 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.SolrCore [[recoverytest_shard1_replica1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J2/temp/solr.cloud.hdfs.HdfsRecoveryZkTest_4A51F0A245DB66BA-001/tempDir-001/node2/recoverytest_shard1_replica1], dataDir=[hdfs://localhost:52553/data/recoverytest/core_node2/data/] [junit4] 2> 395839 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7d1c32c2 [junit4] 2> 395840 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:52553/data/recoverytest/core_node2/data/snapshot_metadata [junit4] 2> 395853 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 395853 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 395853 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [16777216] will allocate [1] slabs and use ~[16777216] bytes [junit4] 2> 395853 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [16777216] will allocate [1] slabs and use ~[16777216] bytes [junit4] 2> 395853 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory Creating new global HDFS BlockCache [junit4] 2> 396525 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 396530 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 396530 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:52553/data/recoverytest/core_node1/data [junit4] 2> 396542 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:52553/data/recoverytest/core_node2/data [junit4] 2> 396567 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:52553/data/recoverytest/core_node1/data/index [junit4] 2> 396577 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 396577 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [16777216] will allocate [1] slabs and use ~[16777216] bytes [junit4] 2> 396586 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 396601 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory creating directory factory for path hdfs://localhost:52553/data/recoverytest/core_node2/data/index [junit4] 2> 396625 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory Number of slabs of block cache [1] with direct memory allocation set to [true] [junit4] 2> 396625 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.HdfsDirectoryFactory Block cache target memory usage, slab size of [16777216] will allocate [1] slabs and use ~[16777216] bytes [junit4] 2> 396640 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.s.b.BlockDirectory Block cache on write is disabled [junit4] 2> 396708 INFO (IPC Server handler 3 on 52553) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59736 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-ebb8b02c-53dd-4ee5-9b34-ef1af77c8d57:NORMAL:127.0.0.1:47429|RBW], ReplicaUC[[DISK]DS-7a3f19cb-7a68-4d9d-8016-95f50db91a2a:NORMAL:127.0.0.1:59736|FINALIZED]]} size 0 [junit4] 2> 396709 INFO (IPC Server handler 5 on 52553) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:47429 is added to blk_1073741825_1001{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7a3f19cb-7a68-4d9d-8016-95f50db91a2a:NORMAL:127.0.0.1:59736|FINALIZED], ReplicaUC[[DISK]DS-20190e19-d895-4e50-accd-fa0584d1391c:NORMAL:127.0.0.1:47429|FINALIZED]]} size 0 [junit4] 2> 396779 INFO (IPC Server handler 0 on 52553) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:47429 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-7a3f19cb-7a68-4d9d-8016-95f50db91a2a:NORMAL:127.0.0.1:59736|RBW], ReplicaUC[[DISK]DS-ebb8b02c-53dd-4ee5-9b34-ef1af77c8d57:NORMAL:127.0.0.1:47429|RBW]]} size 0 [junit4] 2> 396780 INFO (IPC Server handler 1 on 52553) [ ] BlockStateChange BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:59736 is added to blk_1073741826_1002{UCState=UNDER_CONSTRUCTION, truncateBlock=null, primaryNodeIndex=-1, replicas=[ReplicaUC[[DISK]DS-ebb8b02c-53dd-4ee5-9b34-ef1af77c8d57:NORMAL:127.0.0.1:47429|RBW], ReplicaUC[[DISK]DS-6dbd0605-0078-4987-b8b0-9e110b95ad66:NORMAL:127.0.0.1:59736|FINALIZED]]} size 0 [junit4] 2> 396901 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog [junit4] 2> 396901 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 396901 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=3 [junit4] 2> 396941 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 396941 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 396942 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.HdfsUpdateLog [junit4] 2> 396942 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 396942 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.HdfsUpdateLog Initializing HdfsUpdateLog: tlogDfsReplication=3 [junit4] 2> 396958 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 396958 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 397011 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.s.SolrIndexSearcher Opening [Searcher@ab97442[recoverytest_shard1_replica2] main] [junit4] 2> 397012 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.s.SolrIndexSearcher Opening [Searcher@7f6e8e57[recoverytest_shard1_replica1] main] [junit4] 2> 397013 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 397014 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 397017 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf [junit4] 2> 397017 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf [junit4] 2> 397018 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 397018 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 397019 INFO (searcherExecutor-1257-thread-1-processing-n:127.0.0.1:51817_solr x:recoverytest_shard1_replica1 s:shard1 c:recoverytest) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.c.SolrCore [recoverytest_shard1_replica1] Registered new searcher Searcher@7f6e8e57[recoverytest_shard1_replica1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 397021 INFO (searcherExecutor-1256-thread-1-processing-n:127.0.0.1:59108_solr x:recoverytest_shard1_replica2 s:shard1 c:recoverytest) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SolrCore [recoverytest_shard1_replica2] Registered new searcher Searcher@ab97442[recoverytest_shard1_replica2] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 397027 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1565008548272799744 [junit4] 2> 397033 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1565008548279091200 [junit4] 2> 397039 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms [junit4] 2> 397040 INFO (zkCallback-324-thread-1-processing-n:127.0.0.1:59108_solr) [n:127.0.0.1:59108_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 397041 INFO (zkCallback-328-thread-3-processing-n:127.0.0.1:51817_solr) [n:127.0.0.1:51817_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 397539 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 397539 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 397539 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/ [junit4] 2> 397540 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.PeerSync PeerSync: core=recoverytest_shard1_replica2 url=https://127.0.0.1:59108/solr START replicas=[https://127.0.0.1:51817/solr/recoverytest_shard1_replica1/] nUpdates=100 [junit4] 2> 397540 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.u.PeerSync PeerSync: core=recoverytest_shard1_replica2 url=https://127.0.0.1:59108/solr DONE. We have no versions. sync failed. [junit4] 2> 397550 INFO (qtp660751640-2300) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.c.S.Request [recoverytest_shard1_replica1] webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0 [junit4] 2> 397557 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate [junit4] 2> 397557 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway [junit4] 2> 397557 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 397562 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/ shard1 [junit4] 2> 397669 INFO (zkCallback-324-thread-1-processing-n:127.0.0.1:59108_solr) [n:127.0.0.1:59108_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 397670 INFO (zkCallback-328-thread-3-processing-n:127.0.0.1:51817_solr) [n:127.0.0.1:51817_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 397713 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 397715 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=recoverytest_shard1_replica2&action=CREATE&numShards=1&collection=recoverytest&shard=shard1&wt=javabin&version=2} status=0 QTime=2955 [junit4] 2> 397822 INFO (zkCallback-324-thread-1-processing-n:127.0.0.1:59108_solr) [n:127.0.0.1:59108_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 397822 INFO (zkCallback-328-thread-3-processing-n:127.0.0.1:51817_solr) [n:127.0.0.1:51817_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 398058 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 x:recoverytest_shard1_replica1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=recoverytest_shard1_replica1&action=CREATE&numShards=1&collection=recoverytest&shard=shard1&wt=javabin&version=2} status=0 QTime=3252 [junit4] 2> 398063 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas [junit4] 2> 398172 INFO (zkCallback-328-thread-1-processing-n:127.0.0.1:51817_solr) [n:127.0.0.1:51817_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 398172 INFO (zkCallback-324-thread-1-processing-n:127.0.0.1:59108_solr) [n:127.0.0.1:59108_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/recoverytest/state.json] for collection [recoverytest] has occurred - updating... (live nodes size: [2]) [junit4] 2> 398594 INFO (OverseerCollectionConfigSetProcessor-97813033937928198-127.0.0.1:51817_solr-n_0000000000) [n:127.0.0.1:51817_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper [junit4] 2> 399064 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=2&collection.configName=conf&maxShardsPerNode=1&name=recoverytest&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=4479 [junit4] 2> 399068 INFO (TEST-HdfsRecoveryZkTest.test-seed#[4A51F0A245DB66BA]) [ ] o.a.s.c.RecoveryZkTest Indexing 7000 documents [junit4] 2> 399232 INFO (qtp660751640-2304) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-0 (1565008550453837824)]} 0 90 [junit4] 2> 399233 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-0 (1565008550453837824)]} 0 125 [junit4] 2> 399235 INFO (qtp660751640-2299) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-0 (1565008550429720576)]} 0 95 [junit4] 2> 399236 INFO (qtp955283801-2296) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-0 (1565008550429720576)]} 0 152 [junit4] 2> 399270 INFO (qtp660751640-2300) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-1 (1565008550592249856)]} 0 28 [junit4] 2> 399270 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-1 (1565008550600638464)]} 0 18 [junit4] 2> 399271 INFO (qtp955283801-2292) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-1 (1565008550592249856)]} 0 32 [junit4] 2> 399271 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-1 (1565008550600638464)]} 0 24 [junit4] 2> 399281 INFO (qtp660751640-2302) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-2 (1565008550628950016)]} 0 4 [junit4] 2> 399281 INFO (qtp955283801-2289) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-2 (1565008550628950016)]} 0 7 [junit4] 2> 399291 INFO (qtp660751640-2303) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-2 (1565008550628950017)]} 0 8 [junit4] 2> 399292 INFO (qtp955283801-2294) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-2 (1565008550628950017)]} 0 18 [junit4] 2> 399295 INFO (qtp660751640-2304) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-3 (1565008550640484352)]} 0 8 [junit4] 2> 399296 INFO (qtp955283801-2290) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-3 (1565008550640484352)]} 0 11 [junit4] 2> 399326 INFO (qtp660751640-2300) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-4 (1565008550654115840)]} 0 25 [junit4] 2> 399326 INFO (qtp660751640-2299) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-3 (1565008550650970112)]} 0 28 [junit4] 2> 399327 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-3 (1565008550650970112)]} 0 32 [junit4] 2> 399327 INFO (qtp955283801-2296) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-4 (1565008550654115840)]} 0 28 [junit4] 2> 399338 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-4 (1565008550687670272)]} 0 5 [junit4] 2> 399339 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-4 (1565008550687670272)]} 0 9 [junit4] 2> 399340 INFO (qtp660751640-2302) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-5 (1565008550688718848)]} 0 6 [junit4] 2> 399341 INFO (qtp955283801-2292) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-5 (1565008550688718848)]} 0 10 [junit4] 2> 399350 INFO (qtp660751640-2303) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-5 (1565008550700253184)]} 0 5 [junit4] 2> 399351 INFO (qtp955283801-2294) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-5 (1565008550700253184)]} 0 9 [junit4] 2> 399352 INFO (qtp660751640-2304) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[2-5 (-1565008550702350336)]} 0 4 [junit4] 2> 399352 INFO (qtp955283801-2289) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[2-5 (-1565008550702350336)]} 0 8 [junit4] 2> 399370 INFO (qtp660751640-2299) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-6 (1565008550714933248)]} 0 11 [junit4] 2> 399372 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-6 (1565008550714933248)]} 0 16 [junit4] 2> 399372 INFO (qtp660751640-2300) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[1-5 (-1565008550715981824)]} 0 11 [junit4] 2> 399372 INFO (qtp955283801-2290) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-5 (-1565008550715981824)]} 0 16 [junit4] 2> 399381 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-7 (1565008550734856192)]} 0 3 [junit4] 2> 399382 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-7 (1565008550734856192)]} 0 6 [junit4] 2> 399394 INFO (qtp660751640-2302) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-6 (1565008550735904768)]} 0 5 [junit4] 2> 399394 INFO (qtp955283801-2296) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-6 (1565008550735904768)]} 0 18 [junit4] 2> 399398 INFO (qtp660751640-2303) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-8 (1565008550748487680)]} 0 4 [junit4] 2> 399398 INFO (qtp955283801-2292) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-8 (1565008550748487680)]} 0 10 [junit4] 2> 399401 INFO (qtp660751640-2304) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-7 (1565008550757924864)]} 0 1 [junit4] 2> 399402 INFO (qtp955283801-2294) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-7 (1565008550757924864)]} 0 4 [junit4] 2> 399407 INFO (qtp660751640-2299) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-9 (1565008550762119168)]} 0 3 [junit4] 2> 399408 INFO (qtp955283801-2289) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-9 (1565008550762119168)]} 0 7 [junit4] 2> 399409 INFO (qtp660751640-2300) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-8 (1565008550765264896)]} 0 2 [junit4] 2> 399409 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-8 (1565008550765264896)]} 0 5 [junit4] 2> 399415 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[2-6 (-1565008550771556352)]} 0 2 [junit4] 2> 399416 INFO (qtp955283801-2290) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[2-6 (-1565008550771556352)]} 0 5 [junit4] 2> 399423 INFO (qtp660751640-2303) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-9 (1565008550772604928)]} 0 8 [junit4] 2> 399424 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-9 (1565008550772604928)]} 0 12 [junit4] 2> 399444 INFO (qtp660751640-2304) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[1-6 (-1565008550788333568)]} 0 2 [junit4] 2> 399445 INFO (qtp955283801-2292) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-6 (-1565008550788333568)]} 0 19 [junit4] 2> 399449 INFO (qtp660751640-2302) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-10 (1565008550807207936)]} 0 2 [junit4] 2> 399449 INFO (qtp955283801-2296) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-10 (1565008550807207936)]} 0 5 [junit4] 2> 399454 INFO (qtp660751640-2299) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-10 (1565008550811402240)]} 0 3 [junit4] 2> 399454 INFO (qtp955283801-2294) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-10 (1565008550811402240)]} 0 6 [junit4] 2> 399458 INFO (qtp660751640-2300) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-11 (1565008550817693696)]} 0 2 [junit4] 2> 399458 INFO (qtp955283801-2289) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-11 (1565008550817693696)]} 0 4 [junit4] 2> 399462 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-11 (1565008550820839424)]} 0 2 [junit4] 2> 399462 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-11 (1565008550820839424)]} 0 4 [junit4] 2> 399465 INFO (qtp660751640-2303) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-12 (1565008550825033728)]} 0 2 [junit4] 2> 399466 INFO (qtp955283801-2290) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-12 (1565008550825033728)]} 0 4 [junit4] 2> 399470 INFO (qtp660751640-2304) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-12 (1565008550829228032)]} 0 2 [junit4] 2> 399471 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-12 (1565008550829228032)]} 0 5 [junit4] 2> 399476 INFO (qtp660751640-2302) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-13 (1565008550834470912)]} 0 4 [junit4] 2> 399477 INFO (qtp955283801-2292) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-13 (1565008550834470912)]} 0 6 [junit4] 2> 399480 INFO (qtp660751640-2299) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-13 (1565008550840762368)]} 0 1 [junit4] 2> 399481 INFO (qtp955283801-2296) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-13 (1565008550840762368)]} 0 4 [junit4] 2> 399484 INFO (qtp660751640-2300) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[2-7 (-1565008550844956672)]} 0 2 [junit4] 2> 399485 INFO (qtp955283801-2294) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[2-7 (-1565008550844956672)]} 0 5 [junit4] 2> 399497 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[1-7 (-1565008550849150976)]} 0 10 [junit4] 2> 399497 INFO (qtp955283801-2289) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-7 (-1565008550849150976)]} 0 13 [junit4] 2> 399502 INFO (qtp660751640-2303) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-14 (1565008550860685312)]} 0 4 [junit4] 2> 399503 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-14 (1565008550860685312)]} 0 7 [junit4] 2> 399505 INFO (qtp660751640-2304) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-14 (1565008550868025344)]} 0 1 [junit4] 2> 399506 INFO (qtp955283801-2290) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-14 (1565008550868025344)]} 0 4 [junit4] 2> 399512 INFO (qtp660751640-2302) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[2-8 (-1565008550873268224)]} 0 1 [junit4] 2> 399513 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[2-8 (-1565008550873268224)]} 0 5 [junit4] 2> 399514 INFO (qtp660751640-2299) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[1-8 (-1565008550875365376)]} 0 1 [junit4] 2> 399514 INFO (qtp955283801-2292) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-8 (-1565008550875365376)]} 0 5 [junit4] 2> 399520 INFO (qtp660751640-2300) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-15 (1565008550881656832)]} 0 1 [junit4] 2> 399521 INFO (qtp955283801-2296) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-15 (1565008550881656832)]} 0 5 [junit4] 2> 399523 INFO (qtp660751640-2305) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-15 (1565008550884802560)]} 0 1 [junit4] 2> 399524 INFO (qtp955283801-2294) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-15 (1565008550884802560)]} 0 6 [junit4] 2> 399531 INFO (qtp660751640-2303) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[2-9 (-1565008550892142592)]} 0 2 [junit4] 2> 399532 INFO (qtp660751640-2304) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[1-9 (-1565008550894239744)]} 0 1 [junit4] 2> 399532 INFO (qtp955283801-2289) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[2-9 (-1565008550892142592)]} 0 7 [junit4] 2> 399533 INFO (qtp955283801-2293) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[1-9 (-1565008550894239744)]} 0 5 [junit4] 2> 399539 INFO (qtp660751640-2302) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[2-16 (1565008550903676928)]} 0 1 [junit4] 2> 399541 INFO (qtp955283801-2295) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2-16 (1565008550903676928)]} 0 5 [junit4] 2> 399542 INFO (qtp660751640-2299) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{add=[1-16 (1565008550904725504)]} 0 1 [junit4] 2> 399542 INFO (qtp955283801-2290) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1-16 (1565008550904725504)]} 0 5 [junit4] 2> 399553 INFO (qtp660751640-2300) [n:127.0.0.1:51817_solr c:recoverytest s:shard1 r:core_node2 x:recoverytest_shard1_replica1] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica1] webapp=/solr path=/update params={update.distrib=FROMLEADER&distrib.from=https://127.0.0.1:59108/solr/recoverytest_shard1_replica2/&wt=javabin&version=2}{delete=[2-14 (-1565008550912065536)]} 0 1 [junit4] 2> 399554 INFO (qtp955283801-2292) [n:127.0.0.1:59108_solr c:recoverytest s:shard1 r:core_node1 x:recoverytest_shard1_replica2] o.a.s.u.p.LogUpdateProcessorFactory [recoverytest_shard1_replica2] webapp=/solr path=/update params={wt=javabin&version=2}{delete=[2-14 (-15650085509120 [...truncated too long message...] xtHandler Stopped o.e.j.s.ServletContextHandler@64cc0de7{/solr,null,UNAVAILABLE} [junit4] 2> 4908149 WARN (zkCallback-3794-thread-2-processing-n:127.0.0.1:35439_solr) [n:127.0.0.1:35439_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 4908150 INFO (jetty-closer-3743-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 4908150 INFO (jetty-closer-3743-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3179dc00{/solr,null,UNAVAILABLE} [junit4] 2> 4908343 WARN (zkCallback-3768-thread-9-processing-n:127.0.0.1:41279_solr) [n:127.0.0.1:41279_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 4908344 INFO (jetty-closer-3743-thread-8) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 4908344 INFO (jetty-closer-3743-thread-8) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b3d6c9b{/solr,null,UNAVAILABLE} [junit4] 2> 4908391 WARN (zkCallback-3792-thread-2-processing-n:127.0.0.1:45284_solr) [n:127.0.0.1:45284_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 4908391 INFO (jetty-closer-3743-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 4908392 INFO (jetty-closer-3743-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@79a1b303{/solr,null,UNAVAILABLE} [junit4] 2> 4908428 WARN (zkCallback-3770-thread-2-processing-n:127.0.0.1:33765_solr) [n:127.0.0.1:33765_solr ] o.a.s.c.c.ZkStateReader ZooKeeper watch triggered, but Solr cannot talk to ZK: [KeeperErrorCode = Session expired for /live_nodes] [junit4] 2> 4908428 INFO (jetty-closer-3743-thread-6) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [junit4] 2> 4908428 INFO (jetty-closer-3743-thread-6) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5afe3e1c{/solr,null,UNAVAILABLE} [junit4] 2> 4908431 INFO (SUITE-TestStressCloudBlindAtomicUpdates-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40039 40039 [junit4] 2> 4908535 INFO (Thread-75012) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40039 40039 [junit4] 2> 4908535 WARN (Thread-75012) [ ] o.a.s.c.ZkTestServer Watch limit violations: [junit4] 2> Maximum concurrent create/delete watches above limit: [junit4] 2> [junit4] 2> 11 /solr/aliases.json [junit4] 2> 11 /solr/clusterprops.json [junit4] 2> 10 /solr/configs/org.apache.solr.cloud.TestStressCloudBlindAtomicUpdates_config-set [junit4] 2> 10 /solr/security.json [junit4] 2> [junit4] 2> Maximum concurrent data watches above limit: [junit4] 2> [junit4] 2> 11 /solr/clusterstate.json [junit4] 2> 10 /solr/collections/test_col/state.json [junit4] 2> 2 /solr/overseer_elect/election/97813314030403613-127.0.0.1:47159_solr-n_0000000007 [junit4] 2> 2 /solr/overseer_elect/election/97813314030403598-127.0.0.1:41279_solr-n_0000000000 [junit4] 2> 2 /solr/overseer_elect/election/97813314030403602-127.0.0.1:33765_solr-n_0000000002 [junit4] 2> 2 /solr/overseer_elect/election/97813314030403609-127.0.0.1:45284_solr-n_0000000003 [junit4] 2> 2 /solr/overseer_elect/election/97813314030403612-127.0.0.1:35439_solr-n_0000000006 [junit4] 2> [junit4] 2> Maximum concurrent children watches above limit: [junit4] 2> [junit4] 2> 237 /solr/overseer/collection-queue-work [junit4] 2> 16 /solr/overseer/queue [junit4] 2> 11 /solr/live_nodes [junit4] 2> 11 /solr/collections [junit4] 2> 4 /solr/overseer/queue-work [junit4] 2> [junit4] 2> 4908536 INFO (SUITE-TestStressCloudBlindAtomicUpdates-seed#[4A51F0A245DB66BA]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> NOTE: leaving temporary files on disk at: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J0/temp/solr.cloud.TestStressCloudBlindAtomicUpdates_4A51F0A245DB66BA-001 [junit4] 2> Apr 18, 2017 10:56:13 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {long_stored_idx=PostingsFormat(name=LuceneFixedGap), long_dv_idx=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=LuceneVarGapFixedInterval), long_dv_stored_idx=PostingsFormat(name=Memory doPackFST= false)}, docValues:{_version_=DocValuesFormat(name=Direct), long_dv_idx=DocValuesFormat(name=Lucene54), long_dv_stored=DocValuesFormat(name=Asserting), id=DocValuesFormat(name=Asserting), long_dv=DocValuesFormat(name=Lucene54), long_dv_stored_idx=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1699, maxMBSortInHeap=6.761163802178279, sim=RandomSimilarity(queryNorm=false,coord=no): {}, locale=tr, timezone=Africa/Douala [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=4,threads=1,free=247742624,total=533200896 [junit4] 2> NOTE: All tests run in this JVM: [TestCSVLoader, TestFreeTextSuggestions, HdfsTlogReplayBufferedWhileIndexingTest, TestDocumentBuilder, TestSchemalessBufferedUpdates, DocumentAnalysisRequestHandlerTest, TestSubQueryTransformer, CoreAdminCreateDiscoverTest, TestPseudoReturnFields, ReturnFieldsTest, TestSolrFieldCacheMBean, TestCollectionAPIs, TestSolrConfigHandler, RestartWhileUpdatingTest, TestFastWriter, SpatialHeatmapFacetsTest, SharedFSAutoReplicaFailoverUtilsTest, AnalyticsMergeStrategyTest, HdfsRestartWhileUpdatingTest, TestRandomFaceting, TestSolrConfigHandlerConcurrent, TestFieldCacheReopen, PreAnalyzedFieldManagedSchemaCloudTest, DirectoryFactoryTest, ClassificationUpdateProcessorTest, TestFastLRUCache, DateFieldTest, OverseerModifyCollectionTest, SolrIndexMetricsTest, TestQuerySenderNoQuery, TestRemoteStreaming, TestUninvertingReader, CurrencyFieldOpenExchangeTest, SolrRequestParserTest, LeaderElectionContextKeyTest, TestOrdValues, TestSmileRequest, TestFieldSortValues, TestRandomDVFaceting, UniqFieldsUpdateProcessorFactoryTest, SolrMetricsIntegrationTest, TestPKIAuthenticationPlugin, TestShortCircuitedRequests, CachingDirectoryFactoryTest, StressHdfsTest, TestPerFieldSimilarityClassic, TestPerFieldSimilarity, BooleanFieldTest, PeerSyncTest, TestTrieFacet, TestHdfsUpdateLog, OutOfBoxZkACLAndCredentialsProvidersTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestConfigSetImmutable, LargeFieldTest, SortSpecParsingTest, TestSolrQueryParserDefaultOperatorResource, TestDistributedStatsComponentCardinality, TestSchemaSimilarityResource, TestPHPSerializedResponseWriter, TestSolr4Spatial, RemoteQueryErrorTest, TestConfigSetsAPIZkFailure, RegexBoostProcessorTest, DefaultValueUpdateProcessorTest, CdcrReplicationHandlerTest, CustomCollectionTest, BasicZkTest, TestManagedResource, TestCloudPseudoReturnFields, SegmentsInfoRequestHandlerTest, TestMacroExpander, CdcrUpdateLogTest, SolrCoreTest, TestFunctionQuery, TestCodecSupport, SolrJmxReporterTest, ZkStateWriterTest, TestJsonFacetRefinement, SolrCoreMetricManagerTest, ScriptEngineTest, TestValueSourceCache, TestMiniSolrCloudCluster, DocValuesMissingTest, OverseerTaskQueueTest, TestManagedSchemaThreadSafety, ClusterStateTest, TestJoin, TestCollationFieldDocValues, TestMissingGroups, TestSQLHandler, RequiredFieldsTest, QueryEqualityTest, TestImplicitCoreProperties, GraphQueryTest, UUIDFieldTest, DocValuesNotIndexedTest, AnalysisAfterCoreReloadTest, TestHighlightDedupGrouping, TestOnReconnectListenerSupport, TestFoldingMultitermQuery, TestBulkSchemaAPI, ExternalFileFieldSortTest, ZkNodePropsTest, TestStressLucene, TolerantUpdateProcessorTest, FileBasedSpellCheckerTest, AlternateDirectoryTest, TestSolrCoreProperties, SynonymTokenizerTest, CollectionReloadTest, TestSolrCloudSnapshots, ClusterStateUpdateTest, TestZkChroot, ShardRoutingCustomTest, TestDistributedSearch, TestCoreContainer, StatsComponentTest, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, BadIndexSchemaTest, TestSort, TestFiltering, TestLazyCores, TestBadConfig, SignatureUpdateProcessorFactoryTest, CoreAdminHandlerTest, SuggesterWFSTTest, PolyFieldTest, NoCacheHeaderTest, SchemaVersionSpecificBehaviorTest, TestUpdate, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, DocValuesMultiTest, SolrInfoMBeanTest, DebugComponentTest, IndexBasedSpellCheckerTest, LukeRequestHandlerTest, StandardRequestHandlerTest, TestReversedWildcardFilterFactory, TestOmitPositions, XmlUpdateRequestHandlerTest, MoreLikeThisHandlerTest, FastVectorHighlighterTest, LoggingHandlerTest, TestJmxIntegration, TestCSVResponseWriter, TestAnalyzedSuggestions, TestPartialUpdateDeduplication, TestComponentsName, TestBinaryResponseWriter, HighlighterConfigTest, TestSolrIndexConfig, ResponseLogComponentTest, TestStressRecovery, CursorPagingTest, TestSimpleTrackingShardHandler, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest, DeleteShardTest, DistribCursorPagingTest, LeaderInitiatedRecoveryOnShardRestartTest, MigrateRouteKeyTest, NodeMutatorTest, OverseerStatusTest, RecoveryAfterSoftCommitTest, ReplaceNodeTest, ShardSplitTest, TestLockTree, TestMiniSolrCloudClusterSSL, TestRandomRequestDistribution, TestRebalanceLeaders, TestRequestStatusCollectionAPI, TestSolrCloudWithKerberosAlt, TestStressCloudBlindAtomicUpdates] [junit4] Completed [528/701 (3!)] on J0 in 248.05s, 5 tests, 1 error <<< FAILURES! [...truncated 63270 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
