Build: https://builds.apache.org/job/Lucene-Solr-NightlyTests-6.5/16/
3 tests failed. FAILED: org.apache.solr.cloud.RollingRestartTest.test Error Message: Unable to restart (#2): CloudJettyRunner [url=https://127.0.0.1:56558/vd_gn/collection1] Stack Trace: java.lang.AssertionError: Unable to restart (#2): CloudJettyRunner [url=https://127.0.0.1:56558/vd_gn/collection1] at __randomizedtesting.SeedInfo.seed([1128EE297BFC1292:997CD1F3D5007F6A]:0) at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at org.apache.solr.cloud.RollingRestartTest.restartWithRolesTest(RollingRestartTest.java:103) at org.apache.solr.cloud.RollingRestartTest.test(RollingRestartTest.java:52) 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) FAILED: org.apache.solr.cloud.ZkSolrClientTest.testMultipleWatchesAsync Error Message: Stack Trace: java.lang.AssertionError at __randomizedtesting.SeedInfo.seed([1128EE297BFC1292:799E3D8BEE18130C]:0) at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertTrue(Assert.java:54) at org.apache.solr.cloud.ZkSolrClientTest.testMultipleWatchesAsync(ZkSolrClientTest.java:257) 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:39707 Stack Trace: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:39707 at __randomizedtesting.SeedInfo.seed([1128EE297BFC1292:997CD1F3D5007F6A]: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 11705 lines...] [junit4] Suite: org.apache.solr.cloud.ZkSolrClientTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.ZkSolrClientTest_1128EE297BFC1292-001/init-core-data-001 [junit4] 2> 636167 WARN (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=11 numCloses=11 [junit4] 2> 636167 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 636176 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-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> 636177 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore [junit4] 2> 636177 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 636313 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.1 [junit4] 2> 636430 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=test [junit4] 2> 636776 WARN (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.s.IndexSchema [null] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 636779 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 636815 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=30000&connTimeout=30000&retry=true [junit4] 2> 636870 WARN (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 636873 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541aa5c6 [junit4] 2> 636873 WARN (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 636906 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541aa5c6 [junit4] 2> 636907 WARN (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 636907 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541aa5c6 [junit4] 2> 636911 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/lib, /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/lib/classes] [junit4] 2> 637018 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.1 [junit4] 2> 637193 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 637361 WARN (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 637363 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 637410 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1 [junit4] 2> 637410 WARN (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter No serviceUrl or agentId was configured, using first MBeanServer. [junit4] 2> 637411 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@541aa5c6 [junit4] 2> 637411 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 637411 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.ZkSolrClientTest_1128EE297BFC1292-001/init-core-data-001/] [junit4] 2> 637412 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@541aa5c6 [junit4] 2> 637413 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.MockRandomMergePolicy: org.apache.lucene.index.MockRandomMergePolicy@4f51742 [junit4] 2> 637528 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 637528 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 637529 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 637529 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 637531 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=22, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.29336782343339385] [junit4] 2> 637537 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@354a7516[collection1] main] [junit4] 2> 637538 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Initializing spell checkers [junit4] 2> 637553 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3} [junit4] 2> 637691 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 637691 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default [junit4] 2> 637691 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct [junit4] 2> 637691 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak [junit4] 2> 637691 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields [junit4] 2> 637692 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler [junit4] 2> 637692 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external [junit4] 2> 637692 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq [junit4] 2> 637693 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn [junit4] 2> 637694 INFO (coreLoadExecutor-1092-thread-1) [ x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1565377769126232064 [junit4] 2> 637696 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict [junit4] 2> 637697 INFO (searcherExecutor-1093-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@354a7516[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 637699 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end [junit4] 2> 637700 INFO (TEST-ZkSolrClientTest.testMultipleWatchesAsync-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMultipleWatchesAsync [junit4] 2> 637701 INFO (TEST-ZkSolrClientTest.testMultipleWatchesAsync-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 637717 INFO (Thread-1203) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 637717 INFO (Thread-1203) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 637816 INFO (TEST-ZkSolrClientTest.testMultipleWatchesAsync-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:54560 [junit4] 2> 640470 INFO (TEST-ZkSolrClientTest.testMultipleWatchesAsync-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54560 54560 [junit4] 2> 640772 INFO (Thread-1203) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54560 54560 [junit4] 2> 640775 INFO (TEST-ZkSolrClientTest.testMultipleWatchesAsync-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMultipleWatchesAsync [junit4] 2> NOTE: download the large Jenkins line-docs file by running 'ant get-jenkins-line-docs' in the lucene directory. [junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ZkSolrClientTest -Dtests.method=testMultipleWatchesAsync -Dtests.seed=1128EE297BFC1292 -Dtests.multiplier=2 -Dtests.nightly=true -Dtests.slow=true -Dtests.linedocsfile=/home/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/test-data/enwiki.random.lines.txt -Dtests.locale=und -Dtests.timezone=Asia/Famagusta -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1 [junit4] FAILURE 3.08s J1 | ZkSolrClientTest.testMultipleWatchesAsync <<< [junit4] > Throwable #1: java.lang.AssertionError [junit4] > at __randomizedtesting.SeedInfo.seed([1128EE297BFC1292:799E3D8BEE18130C]:0) [junit4] > at org.apache.solr.cloud.ZkSolrClientTest.testMultipleWatchesAsync(ZkSolrClientTest.java:257) [junit4] > at java.lang.Thread.run(Thread.java:745) [junit4] 2> 640794 INFO (TEST-ZkSolrClientTest.testSkipPathPartsOnMakePath-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSkipPathPartsOnMakePath [junit4] 2> 640794 INFO (TEST-ZkSolrClientTest.testSkipPathPartsOnMakePath-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 640796 INFO (Thread-1204) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 640796 INFO (Thread-1204) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 640896 INFO (TEST-ZkSolrClientTest.testSkipPathPartsOnMakePath-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:37061 [junit4] 2> 640995 INFO (TEST-ZkSolrClientTest.testSkipPathPartsOnMakePath-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37061 37061 [junit4] 2> 641235 INFO (Thread-1204) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37061 37061 [junit4] 2> 641236 INFO (TEST-ZkSolrClientTest.testSkipPathPartsOnMakePath-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSkipPathPartsOnMakePath [junit4] 2> 641239 INFO (TEST-ZkSolrClientTest.testWatchChildren-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWatchChildren [junit4] 2> 641239 INFO (TEST-ZkSolrClientTest.testWatchChildren-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 641247 INFO (Thread-1205) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 641247 INFO (Thread-1205) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 641346 INFO (TEST-ZkSolrClientTest.testWatchChildren-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:40356 [junit4] 2> 642214 INFO (TEST-ZkSolrClientTest.testWatchChildren-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40356 40356 [junit4] 2> 642451 INFO (Thread-1205) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:40356 40356 [junit4] 2> 642452 INFO (TEST-ZkSolrClientTest.testWatchChildren-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWatchChildren [junit4] 2> 642455 INFO (TEST-ZkSolrClientTest.testZkCmdExectutor-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testZkCmdExectutor [junit4] 2> 642455 INFO (TEST-ZkSolrClientTest.testZkCmdExectutor-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 642478 INFO (Thread-1206) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 642478 INFO (Thread-1206) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 642666 INFO (TEST-ZkSolrClientTest.testZkCmdExectutor-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:43035 [junit4] 2> 657868 INFO (TEST-ZkSolrClientTest.testZkCmdExectutor-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43035 43035 [junit4] 2> 658077 INFO (Thread-1206) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43035 43035 [junit4] 2> 658079 INFO (TEST-ZkSolrClientTest.testZkCmdExectutor-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testZkCmdExectutor [junit4] 2> 658081 INFO (TEST-ZkSolrClientTest.testClean-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testClean [junit4] 2> 658082 INFO (TEST-ZkSolrClientTest.testClean-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 658087 INFO (Thread-1207) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 658087 INFO (Thread-1207) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 658187 INFO (TEST-ZkSolrClientTest.testClean-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:43837 [junit4] 2> 658292 INFO (TEST-ZkSolrClientTest.testClean-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43837 43837 [junit4] 2> 658439 INFO (Thread-1207) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43837 43837 [junit4] 2> 658440 INFO (TEST-ZkSolrClientTest.testClean-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testClean [junit4] 2> 658442 INFO (TEST-ZkSolrClientTest.testMakeRootNode-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMakeRootNode [junit4] 2> 658443 INFO (TEST-ZkSolrClientTest.testMakeRootNode-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 658459 INFO (Thread-1208) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 658460 INFO (Thread-1208) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 658559 INFO (TEST-ZkSolrClientTest.testMakeRootNode-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:34705 [junit4] 2> 658599 INFO (TEST-ZkSolrClientTest.testMakeRootNode-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34705 34705 [junit4] 2> 658849 INFO (Thread-1208) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:34705 34705 [junit4] 2> 658852 INFO (TEST-ZkSolrClientTest.testMakeRootNode-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMakeRootNode [junit4] 2> 658854 INFO (TEST-ZkSolrClientTest.testReconnect-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testReconnect [junit4] 2> 658855 INFO (TEST-ZkSolrClientTest.testReconnect-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 658869 INFO (Thread-1209) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 658869 INFO (Thread-1209) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 658970 INFO (TEST-ZkSolrClientTest.testReconnect-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:43251 [junit4] 2> 659062 INFO (TEST-ZkSolrClientTest.testReconnect-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43251 43251 [junit4] 2> 659209 INFO (Thread-1209) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43251 43251 [junit4] 2> 659309 INFO (TEST-ZkSolrClientTest.testReconnect-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 659309 WARN (zkCallback-394-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@21d93bb7 name: ZooKeeperConnection Watcher:127.0.0.1:43251/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 659309 WARN (zkCallback-394-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 659324 INFO (Thread-1210) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:43251 [junit4] 2> 659324 INFO (Thread-1210) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 659415 INFO (TEST-ZkSolrClientTest.testReconnect-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:43251 [junit4] 2> 660925 WARN (zkCallback-394-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@21d93bb7 name: ZooKeeperConnection Watcher:127.0.0.1:43251/solr got event WatchedEvent state:Disconnected type:None path:null path: null type: None [junit4] 2> 660925 WARN (zkCallback-394-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has disconnected [junit4] 2> 661982 WARN (zkCallback-394-thread-1) [ ] o.a.s.c.c.ConnectionManager Watcher org.apache.solr.common.cloud.ConnectionManager@21d93bb7 name: ZooKeeperConnection Watcher:127.0.0.1:43251/solr got event WatchedEvent state:Expired type:None path:null path: null type: None [junit4] 2> 661982 WARN (zkCallback-394-thread-1) [ ] o.a.s.c.c.ConnectionManager Our previous ZooKeeper session was expired. Attempting to reconnect to recover relationship with ZooKeeper... [junit4] 2> 661982 WARN (zkCallback-394-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Connection expired - starting a new one... [junit4] 2> 662025 INFO (zkCallback-394-thread-1) [ ] o.a.s.c.c.ConnectionManager Connection with ZooKeeper reestablished. [junit4] 2> 662025 INFO (zkCallback-394-thread-1) [ ] o.a.s.c.c.DefaultConnectionStrategy Reconnected to ZooKeeper [junit4] 2> 662025 INFO (zkCallback-394-thread-1) [ ] o.a.s.c.c.ConnectionManager Connected:true [junit4] 2> 662085 INFO (TEST-ZkSolrClientTest.testReconnect-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43251 43251 [junit4] 2> 662085 INFO (Thread-1210) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:43251 43251 [junit4] 2> 662086 INFO (TEST-ZkSolrClientTest.testReconnect-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testReconnect [junit4] 2> 662092 INFO (TEST-ZkSolrClientTest.testConnect-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testConnect [junit4] 2> 662093 INFO (TEST-ZkSolrClientTest.testConnect-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 662100 INFO (Thread-1211) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 662100 INFO (Thread-1211) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 662211 INFO (TEST-ZkSolrClientTest.testConnect-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:54439 [junit4] 2> 662266 INFO (TEST-ZkSolrClientTest.testConnect-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54439 54439 [junit4] 2> 662412 INFO (Thread-1211) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:54439 54439 [junit4] 2> 662413 INFO (TEST-ZkSolrClientTest.testConnect-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testConnect [junit4] 2> 664417 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore [junit4] 2> 664417 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1358149624 [junit4] 2> 664433 INFO (coreCloseExecutor-1098-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@606e5e5d [junit4] 2> 664497 INFO (coreCloseExecutor-1098-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.core.collection1 [junit4] 2> 664500 INFO (SUITE-ZkSolrClientTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for: solr.node [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/J1/temp/solr.cloud.ZkSolrClientTest_1128EE297BFC1292-001 [junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {}, docValues:{}, maxPointsInLeafNode=792, maxMBSortInHeap=6.955855826295413, sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=und, timezone=Asia/Famagusta [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=4,threads=1,free=172131368,total=496500736 [junit4] 2> NOTE: All tests run in this JVM: [TestSSLRandomization, TestLocalFSCloudBackupRestore, DateMathParserTest, SparseHLLTest, TestSweetSpotSimilarityFactory, ConvertedLegacyTest, TestWordDelimiterFilterFactory, ShardRoutingTest, JsonLoaderTest, TestScoreJoinQPNoScore, BlockJoinFacetRandomTest, TestQueryUtils, TestFieldCacheVsDocValues, TestPartialUpdateDeduplication, DataDrivenBlockJoinTest, TestDistribIDF, TestBlendedInfixSuggestions, DeleteInactiveReplicaTest, CollectionsAPISolrJTest, TestPointFields, TestStandardQParsers, TestQueryTypes, CloudMLTQParserTest, ForceLeaderTest, TestSubQueryTransformerCrossCore, HighlighterTest, SimplePostToolTest, DirectSolrConnectionTest, MoreLikeThisHandlerTest, ExitableDirectoryReaderTest, SpellCheckCollatorTest, IndexSchemaTest, MetricsHandlerTest, TestBackupRepositoryFactory, TestReqParamsAPI, SpatialFilterTest, ZkSolrClientTest] [junit4] Completed [62/701 (1!)] on J1 in 28.41s, 8 tests, 1 failure <<< FAILURES! [...truncated 1614 lines...] [junit4] Suite: org.apache.solr.cloud.RollingRestartTest [junit4] 2> Creating dataDir: /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/init-core-data-001 [junit4] 2> 4981363 WARN (SUITE-RollingRestartTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3 [junit4] 2> 4981363 INFO (SUITE-RollingRestartTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields [junit4] 2> 4981365 INFO (SUITE-RollingRestartTest-seed#[1128EE297BFC1292]-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> 4981365 INFO (SUITE-RollingRestartTest-seed#[1128EE297BFC1292]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /vd_gn/ [junit4] 2> 4981368 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER [junit4] 2> 4981368 INFO (Thread-21377) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4981368 INFO (Thread-21377) [ ] o.a.s.c.ZkTestServer Starting server [junit4] 2> 4981468 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkTestServer start zk server on port:38271 [junit4] 2> 4981483 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 4981485 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml [junit4] 2> 4981486 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4981488 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 4981489 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 4981490 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 4981491 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 4981493 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 4981494 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4981495 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 4981496 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractZkTestCase put /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 4981608 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/control-001/cores/collection1 [junit4] 2> 4981610 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 4981612 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@62313e4e{/vd_gn,null,AVAILABLE} [junit4] 2> 4981613 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@32b65bda{SSL,[ssl, http/1.1]}{127.0.0.1:40047} [junit4] 2> 4981613 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.Server Started @4988080ms [junit4] 2> 4981613 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/tempDir-001/control/data, hostContext=/vd_gn, hostPort=40047, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/control-001/cores} [junit4] 2> 4981614 ERROR (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4981614 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.1 [junit4] 2> 4981614 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 4981614 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 4981614 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-04-22T12:41:57.809Z [junit4] 2> 4981618 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4981618 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/control-001/solr.xml [junit4] 2> 4981632 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4981639 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38271/solr [junit4] 2> 4981680 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4981681 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40047_vd_gn [junit4] 2> 4981682 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.Overseer Overseer (id=97836395237474309-127.0.0.1:40047_vd_gn-n_0000000000) starting [junit4] 2> 4981689 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40047_vd_gn [junit4] 2> 4981696 INFO (zkCallback-31020-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 4981897 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/control-001/cores [junit4] 2> 4981897 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 4981905 INFO (OverseerStateUpdate-97836395237474309-127.0.0.1:40047_vd_gn-n_0000000000) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 4981926 WARN (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 4981927 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.1 [junit4] 2> 4981958 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 4983286 WARN (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 4983288 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 4983301 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4983302 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4983302 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/control-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/control-001/cores/collection1/data/] [junit4] 2> 4983302 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@541aa5c6 [junit4] 2> 4983304 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 4983317 WARN (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 4983362 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4983363 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4983363 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4983363 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4983364 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=34.2099609375, floorSegmentMB=0.302734375, forceMergeDeletesPctAllowed=18.85065924776351, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3116993640268799 [junit4] 2> 4983365 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@33f98d1e[collection1] main] [junit4] 2> 4983366 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4983367 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4983367 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 4983368 INFO (coreLoadExecutor-12029-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1565382325895692288 [junit4] 2> 4983371 INFO (searcherExecutor-12030-thread-1-processing-n:127.0.0.1:40047_vd_gn x:collection1 c:control_collection) [n:127.0.0.1:40047_vd_gn c:control_collection x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@33f98d1e[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4983376 INFO (coreZkRegister-12022-thread-1-processing-n:127.0.0.1:40047_vd_gn x:collection1 c:control_collection) [n:127.0.0.1:40047_vd_gn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 4983376 INFO (coreZkRegister-12022-thread-1-processing-n:127.0.0.1:40047_vd_gn x:collection1 c:control_collection) [n:127.0.0.1:40047_vd_gn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 4983376 INFO (coreZkRegister-12022-thread-1-processing-n:127.0.0.1:40047_vd_gn x:collection1 c:control_collection) [n:127.0.0.1:40047_vd_gn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40047/vd_gn/collection1/ [junit4] 2> 4983376 INFO (coreZkRegister-12022-thread-1-processing-n:127.0.0.1:40047_vd_gn x:collection1 c:control_collection) [n:127.0.0.1:40047_vd_gn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 4983376 INFO (coreZkRegister-12022-thread-1-processing-n:127.0.0.1:40047_vd_gn x:collection1 c:control_collection) [n:127.0.0.1:40047_vd_gn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:40047/vd_gn/collection1/ has no replicas [junit4] 2> 4983376 INFO (coreZkRegister-12022-thread-1-processing-n:127.0.0.1:40047_vd_gn x:collection1 c:control_collection) [n:127.0.0.1:40047_vd_gn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 4983381 INFO (coreZkRegister-12022-thread-1-processing-n:127.0.0.1:40047_vd_gn x:collection1 c:control_collection) [n:127.0.0.1:40047_vd_gn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:40047/vd_gn/collection1/ shard1 [junit4] 2> 4983534 INFO (coreZkRegister-12022-thread-1-processing-n:127.0.0.1:40047_vd_gn x:collection1 c:control_collection) [n:127.0.0.1:40047_vd_gn c:control_collection s:shard1 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 4983711 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 4983712 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38271/solr ready [junit4] 2> 4983712 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4983712 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating collection1 with stateFormat=2 [junit4] 2> 4983832 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-1-001/cores/collection1 [junit4] 2> 4983833 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-1-001 [junit4] 2> 4983835 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 4983837 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@c3bda23{/vd_gn,null,AVAILABLE} [junit4] 2> 4983837 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@78a81f5c{SSL,[ssl, http/1.1]}{127.0.0.1:42883} [junit4] 2> 4983837 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.Server Started @4990304ms [junit4] 2> 4983837 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/vd_gn, hostPort=42883, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-1-001/cores} [junit4] 2> 4983838 ERROR (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4983838 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.1 [junit4] 2> 4983838 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 4983838 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 4983838 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-04-22T12:42:00.033Z [junit4] 2> 4983842 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4983842 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-1-001/solr.xml [junit4] 2> 4983849 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4983850 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38271/solr [junit4] 2> 4983858 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1) [junit4] 2> 4983861 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4983863 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42883_vd_gn [junit4] 2> 4983870 INFO (zkCallback-31024-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 4983871 INFO (zkCallback-31020-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 4983872 INFO (zkCallback-31030-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2) [junit4] 2> 4983953 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-1-001/cores [junit4] 2> 4983953 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 4983956 INFO (OverseerStateUpdate-97836395237474309-127.0.0.1:40047_vd_gn-n_0000000000) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard2 [junit4] 2> 4984058 INFO (zkCallback-31030-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 4984980 WARN (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 4984981 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.1 [junit4] 2> 4984995 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 4985093 WARN (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 4985095 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 4985107 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4985107 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4985107 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-1-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-1-001/cores/collection1/data/] [junit4] 2> 4985107 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@541aa5c6 [junit4] 2> 4985109 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 4985124 WARN (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 4985173 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4985173 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4985173 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4985173 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4985174 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=34.2099609375, floorSegmentMB=0.302734375, forceMergeDeletesPctAllowed=18.85065924776351, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3116993640268799 [junit4] 2> 4985175 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6d918dc6[collection1] main] [junit4] 2> 4985176 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4985176 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4985176 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 4985177 INFO (coreLoadExecutor-12040-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1565382327792566272 [junit4] 2> 4985181 INFO (searcherExecutor-12041-thread-1-processing-n:127.0.0.1:42883_vd_gn x:collection1 c:collection1) [n:127.0.0.1:42883_vd_gn c:collection1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6d918dc6[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4985188 INFO (coreZkRegister-12035-thread-1-processing-n:127.0.0.1:42883_vd_gn x:collection1 c:collection1) [n:127.0.0.1:42883_vd_gn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 4985188 INFO (coreZkRegister-12035-thread-1-processing-n:127.0.0.1:42883_vd_gn x:collection1 c:collection1) [n:127.0.0.1:42883_vd_gn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 4985188 INFO (coreZkRegister-12035-thread-1-processing-n:127.0.0.1:42883_vd_gn x:collection1 c:collection1) [n:127.0.0.1:42883_vd_gn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:42883/vd_gn/collection1/ [junit4] 2> 4985188 INFO (coreZkRegister-12035-thread-1-processing-n:127.0.0.1:42883_vd_gn x:collection1 c:collection1) [n:127.0.0.1:42883_vd_gn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me [junit4] 2> 4985188 INFO (coreZkRegister-12035-thread-1-processing-n:127.0.0.1:42883_vd_gn x:collection1 c:collection1) [n:127.0.0.1:42883_vd_gn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.SyncStrategy https://127.0.0.1:42883/vd_gn/collection1/ has no replicas [junit4] 2> 4985188 INFO (coreZkRegister-12035-thread-1-processing-n:127.0.0.1:42883_vd_gn x:collection1 c:collection1) [n:127.0.0.1:42883_vd_gn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR [junit4] 2> 4985194 INFO (coreZkRegister-12035-thread-1-processing-n:127.0.0.1:42883_vd_gn x:collection1 c:collection1) [n:127.0.0.1:42883_vd_gn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:42883/vd_gn/collection1/ shard2 [junit4] 2> 4985298 INFO (zkCallback-31030-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 4985300 INFO (coreZkRegister-12035-thread-1-processing-n:127.0.0.1:42883_vd_gn x:collection1 c:collection1) [n:127.0.0.1:42883_vd_gn c:collection1 s:shard2 r:core_node1 x:collection1] o.a.s.c.ZkController I am the leader, no recovery necessary [junit4] 2> 4985406 INFO (zkCallback-31030-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [2]) [junit4] 2> 4985635 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-2-001/cores/collection1 [junit4] 2> 4985636 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-2-001 [junit4] 2> 4985638 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.Server jetty-9.3.14.v20161028 [junit4] 2> 4985639 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@682e5c6{/vd_gn,null,AVAILABLE} [junit4] 2> 4985640 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@14ca9279{SSL,[ssl, http/1.1]}{127.0.0.1:60852} [junit4] 2> 4985640 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.e.j.s.Server Started @4992107ms [junit4] 2> 4985640 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/vd_gn, hostPort=60852, coreRootDirectory=/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-2-001/cores} [junit4] 2> 4985640 ERROR (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete. [junit4] 2> 4985641 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 6.5.1 [junit4] 2> 4985641 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null [junit4] 2> 4985641 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null [junit4] 2> 4985641 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-04-22T12:42:01.836Z [junit4] 2> 4985651 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper) [junit4] 2> 4985651 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-2-001/solr.xml [junit4] 2> 4985658 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.u.UpdateShardHandler Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4985659 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38271/solr [junit4] 2> 4985669 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:60852_vd_gn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2) [junit4] 2> 4985671 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:60852_vd_gn ] o.a.s.c.Overseer Overseer (id=null) closing [junit4] 2> 4985673 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:60852_vd_gn ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60852_vd_gn [junit4] 2> 4985675 INFO (zkCallback-31030-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 4985675 INFO (zkCallback-31024-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 4985675 INFO (zkCallback-31020-thread-1-processing-n:127.0.0.1:40047_vd_gn) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 4985708 INFO (zkCallback-31036-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3) [junit4] 2> 4985766 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:60852_vd_gn ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-2-001/cores [junit4] 2> 4985766 INFO (TEST-RollingRestartTest.test-seed#[1128EE297BFC1292]) [n:127.0.0.1:60852_vd_gn ] o.a.s.c.CorePropertiesLocator Cores are: [collection1] [junit4] 2> 4985773 INFO (OverseerStateUpdate-97836395237474309-127.0.0.1:40047_vd_gn-n_0000000000) [n:127.0.0.1:40047_vd_gn ] o.a.s.c.o.ReplicaMutator Assigning new node to shard shard=shard1 [junit4] 2> 4985899 INFO (zkCallback-31036-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 4985899 INFO (zkCallback-31030-thread-1-processing-n:127.0.0.1:42883_vd_gn) [n:127.0.0.1:42883_vd_gn ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [3]) [junit4] 2> 4986790 WARN (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.c.Config Beginning with Solr 5.5, <mergePolicy> is deprecated, use <mergePolicyFactory> instead. [junit4] 2> 4986791 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 6.5.1 [junit4] 2> 4986813 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=test [junit4] 2> 4986979 WARN (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.s.IndexSchema [collection1] default search field in schema is text. WARNING: Deprecated, please use 'df' on request instead. [junit4] 2> 4986981 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id [junit4] 2> 4987021 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4987021 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder [junit4] 2> 4987021 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-2-001/cores/collection1], dataDir=[/x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/../../../../../../../../../../../x1/jenkins/jenkins-slave/workspace/Lucene-Solr-NightlyTests-6.5/checkout/solr/build/solr-core/test/J1/temp/solr.cloud.RollingRestartTest_1128EE297BFC1292-001/shard-2-001/cores/collection1/data/] [junit4] 2> 4987022 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.c.JmxMonitoredMap JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@541aa5c6 [junit4] 2> 4987036 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=42, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 4987057 WARN (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}} [junit4] 2> 4987195 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4987195 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536 [junit4] 2> 4987196 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled [junit4] 2> 4987196 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled [junit4] 2> 4987197 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=28, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=34.2099609375, floorSegmentMB=0.302734375, forceMergeDeletesPctAllowed=18.85065924776351, segmentsPerTier=50.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3116993640268799 [junit4] 2> 4987197 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@6e1decc3[collection1] main] [junit4] 2> 4987199 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4987199 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4987200 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 [junit4] 2> 4987201 INFO (coreLoadExecutor-12051-thread-1-processing-n:127.0.0.1:60852_vd_gn) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1565382329914884096 [junit4] 2> 4987207 INFO (searcherExecutor-12052-thread-1-processing-n:127.0.0.1:60852_vd_gn x:collection1 c:collection1) [n:127.0.0.1:60852_vd_gn c:collection1 x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@6e1decc3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4987213 INFO (coreZkRegister-12046-thread-1-processing-n:127.0.0.1:60852_vd_gn x:collection1 c:collection1) [n:127.0.0.1:60852_vd_gn c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue. [junit4] 2> 4987213 INFO (coreZkRegister-12046-thread-1-processing-n:127.0.0.1:60852_vd_gn x:collection1 c:collection1) [n:127.0.0.1:60852_vd_gn c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync [junit4] 2> 4987213 INFO (coreZkRegister-12046-thread-1-processing-n:127.0.0.1:60852_vd_gn x:collection1 c:collection1) [n:127.0.0.1:60852_vd_gn c:collection1 s:shard1 r:core_node2 x:collection1] o.a.s.c.SyncStrategy [...truncated too long message...] 2> at com.sun.jmx.mbeanserver.JmxMBeanServer.unregisterMBean(JmxMBeanServer.java:546) [junit4] 2> at org.apache.hadoop.metrics2.util.MBeans.unregister(MBeans.java:81) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stopMBeans(MetricsSourceAdapter.java:226) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSourceAdapter.stop(MetricsSourceAdapter.java:211) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stopSources(MetricsSystemImpl.java:463) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.stop(MetricsSystemImpl.java:213) [junit4] 2> at org.apache.hadoop.metrics2.impl.MetricsSystemImpl.shutdown(MetricsSystemImpl.java:594) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdownInstance(DefaultMetricsSystem.java:72) [junit4] 2> at org.apache.hadoop.metrics2.lib.DefaultMetricsSystem.shutdown(DefaultMetricsSystem.java:68) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.metrics.NameNodeMetrics.shutdown(NameNodeMetrics.java:171) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.NameNode.stop(NameNode.java:872) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1726) [junit4] 2> at org.apache.hadoop.hdfs.MiniDFSCluster.shutdown(MiniDFSCluster.java:1705) [junit4] 2> at org.apache.solr.cloud.hdfs.HdfsTestUtil.teardownClass(HdfsTestUtil.java:203) [junit4] 2> at org.apache.solr.cloud.hdfs.StressHdfsTest.teardownClass(StressHdfsTest.java:77) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [junit4] 2> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) [junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) [junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1713) [junit4] 2> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:870) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [junit4] 2> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [junit4] 2> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [junit4] 2> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [junit4] 2> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [junit4] 2> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> Caused by: java.lang.NullPointerException [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlocksMap.size(BlocksMap.java:203) [junit4] 2> at org.apache.hadoop.hdfs.server.blockmanagement.BlockManager.getTotalBlocks(BlockManager.java:3370) [junit4] 2> at org.apache.hadoop.hdfs.server.namenode.FSNamesystem.getBlocksTotal(FSNamesystem.java:5729) [junit4] 2> ... 53 more [junit4] 2> 5615912 INFO (SUITE-StressHdfsTest-seed#[1128EE297BFC1292]-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/J1/temp/solr.cloud.hdfs.StressHdfsTest_1128EE297BFC1292-001 [junit4] 2> Apr 22, 2017 12:52:32 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks [junit4] 2> WARNING: Will linger awaiting termination of 34 leaked thread(s). [junit4] 2> NOTE: test params are: codec=Asserting(Lucene62): {range_facet_l_dv=PostingsFormat(name=Asserting), multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), intDefault=PostingsFormat(name=Memory doPackFST= false), id=PostingsFormat(name=Asserting), range_facet_i_dv=PostingsFormat(name=Memory doPackFST= false), text=FSTOrd50, range_facet_l=PostingsFormat(name=Memory doPackFST= false), timestamp=PostingsFormat(name=Memory doPackFST= false), txt_t=FSTOrd50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Lucene54), _version_=DocValuesFormat(name=Direct), multiDefault=DocValuesFormat(name=Asserting), intDefault=DocValuesFormat(name=Direct), range_facet_i_dv=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Lucene54), text=DocValuesFormat(name=Lucene54), intDvoDefault=DocValuesFormat(name=Lucene54), timestamp=DocValuesFormat(name=Direct), range_facet_l=DocValuesFormat(name=Direct), txt_t=DocValuesFormat(name=Lucene54)}, maxPointsInLeafNode=1411, maxMBSortInHeap=5.850613561082991, sim=RandomSimilarity(queryNorm=true,coord=no): {}, locale=sl-SI, timezone=America/North_Dakota/Beulah [junit4] 2> NOTE: Linux 3.13.0-85-generic amd64/Oracle Corporation 1.8.0_121 (64-bit)/cpus=4,threads=1,free=220235472,total=535298048 [junit4] 2> NOTE: All tests run in this JVM: [TestSSLRandomization, TestLocalFSCloudBackupRestore, DateMathParserTest, SparseHLLTest, TestSweetSpotSimilarityFactory, ConvertedLegacyTest, TestWordDelimiterFilterFactory, ShardRoutingTest, JsonLoaderTest, TestScoreJoinQPNoScore, BlockJoinFacetRandomTest, TestQueryUtils, TestFieldCacheVsDocValues, TestPartialUpdateDeduplication, DataDrivenBlockJoinTest, TestDistribIDF, TestBlendedInfixSuggestions, DeleteInactiveReplicaTest, CollectionsAPISolrJTest, TestPointFields, TestStandardQParsers, TestQueryTypes, CloudMLTQParserTest, ForceLeaderTest, TestSubQueryTransformerCrossCore, HighlighterTest, SimplePostToolTest, DirectSolrConnectionTest, MoreLikeThisHandlerTest, ExitableDirectoryReaderTest, SpellCheckCollatorTest, IndexSchemaTest, MetricsHandlerTest, TestBackupRepositoryFactory, TestReqParamsAPI, SpatialFilterTest, ZkSolrClientTest, TestDynamicFieldCollectionResource, TestGeoJSONResponseWriter, IndexBasedSpellCheckerTest, DistributedFacetPivotLongTailTest, TestNumericTerms32, TestSlowCompositeReaderWrapper, VersionInfoTest, TestCoreContainer, TestJsonFacets, SolrXmlInZkTest, ZkCLITest, PKIAuthenticationIntegrationTest, SolrMetricManagerTest, TestManagedSynonymFilterFactory, SystemInfoHandlerTest, DistributedQueueTest, TestBM25SimilarityFactory, TestCSVResponseWriter, TestConfigReload, DistributedSuggestComponentTest, TestHighFrequencyDictionaryFactory, TestHashPartitioner, TestClusterStateMutator, ReplaceNodeTest, SortByFunctionTest, MergeStrategyTest, TestConfigSetProperties, TestConfigSetsAPI, DistributedFacetPivotWhiteBoxTest, TestSQLHandlerNonCloud, ShardSplitTest, AnalyticsQueryTest, TestConfigsApi, EchoParamsTest, HdfsSyncSliceTest, ConnectionManagerTest, TestMultiWordSynonyms, DistributedExpandComponentTest, TestLMDirichletSimilarityFactory, ConfigureRecoveryStrategyTest, TestCustomStream, HdfsRecoverLeaseTest, TestSystemCollAutoCreate, TestElisionMultitermQuery, CloneFieldUpdateProcessorFactoryTest, ChaosMonkeySafeLeaderTest, UUIDUpdateProcessorFallbackTest, DeleteReplicaTest, TestLRUStatsCache, TestCloudRecovery, TestSolrCoreSnapshots, TestFiltering, DirectSolrSpellCheckerTest, TestSolrCloudWithSecureImpersonation, TestSizeLimitedDistributedMap, ResponseHeaderTest, DistributedIntervalFacetingTest, TestSolr4Spatial2, TestStressLiveNodes, TestSolrQueryParserResource, TestObjectReleaseTracker, TermVectorComponentTest, TlogReplayBufferedWhileIndexingTest, TestManagedSchema, ParsingFieldUpdateProcessorsTest, TestSchemaVersionResource, TestQuerySenderListener, BlobRepositoryCloudTest, TestDownShardTolerantSearch, TestAddFieldRealTimeGet, TestInitQParser, TestLeaderElectionWithEmptyReplica, SolrSlf4jReporterTest, HdfsBasicDistributedZk2Test, TestDistributedGrouping, TestRandomFlRTGCloud, MultiThreadedOCPTest, DistributedTermsComponentTest, TestFaceting, TestRecovery, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestJoin, TestStressVersions, TestRangeQuery, TestSimpleTrackingShardHandler, TestPathTrie, TestEmbeddedSolrServerConstructors, ConnectionReuseTest, AssignTest, AsyncCallRequestStatusResponseTest, CdcrReplicationHandlerTest, CleanupOldIndexTest, CollectionReloadTest, CollectionStateFormat2Test, CollectionTooManyReplicasTest, DeleteShardTest, DistribJoinFromCollectionTest, DistributedVersionInfoTest, DocValuesNotIndexedTest, LeaderFailoverAfterPartitionTest, OverseerStatusTest, ReplicationFactorTest, RollingRestartTest, StressHdfsTest] [junit4] Completed [565/701 (3!)] on J1 in 212.85s, 1 test, 1 error <<< FAILURES! [...truncated 63142 lines...]
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
