You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2018/06/04 04:43:20 UTC
[JENKINS] Lucene-Solr-BadApples-master-Linux (64bit/jdk1.8.0_172) -
Build # 45 - Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-master-Linux/45/
Java: 64bit/jdk1.8.0_172 -XX:+UseCompressedOops -XX:+UseSerialGC
5 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testSearchRate
Error Message:
java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
Stack Trace:
java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([828C65886443FEE9:DFC47B01AB8558A6]:0)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:621)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager$1.request(SimCloudManager.java:210)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.setupTest(TestLargeCluster.java:108)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:968)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:748)
Caused by: java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
at java.util.concurrent.FutureTask.report(FutureTask.java:122)
at java.util.concurrent.FutureTask.get(FutureTask.java:192)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:619)
... 41 more
Caused by: java.io.IOException: java.lang.NullPointerException
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:679)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$15(SimCloudManager.java:618)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
... 1 more
Caused by: java.lang.NullPointerException
at org.apache.solr.client.solrj.cloud.autoscaling.Preference.compare(Preference.java:72)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy.setApproxValuesAndSortNodes(Policy.java:390)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy$Session.applyRules(Policy.java:315)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy$Session.<init>(Policy.java:277)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy.createSession(Policy.java:413)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:663)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.setAutoScalingConfig(AutoScalingHandler.java:650)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:215)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:669)
... 6 more
FAILED: org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testSearchRate
Error Message:
java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
Stack Trace:
java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([828C65886443FEE9:DFC47B01AB8558A6]:0)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:621)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager$1.request(SimCloudManager.java:210)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.setupTest(TestLargeCluster.java:108)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:968)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:748)
Caused by: java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
at java.util.concurrent.FutureTask.report(FutureTask.java:122)
at java.util.concurrent.FutureTask.get(FutureTask.java:192)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:619)
... 41 more
Caused by: java.io.IOException: java.lang.NullPointerException
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:679)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$15(SimCloudManager.java:618)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
... 1 more
Caused by: java.lang.NullPointerException
at org.apache.solr.client.solrj.cloud.autoscaling.Preference.compare(Preference.java:72)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy.setApproxValuesAndSortNodes(Policy.java:390)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy$Session.applyRules(Policy.java:315)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy$Session.<init>(Policy.java:277)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy.createSession(Policy.java:413)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:663)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.setAutoScalingConfig(AutoScalingHandler.java:650)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:215)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:669)
... 6 more
FAILED: org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testNodeLost
Error Message:
java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
Stack Trace:
java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([828C65886443FEE9:3D99AB76E7A99B6F]:0)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:621)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager$1.request(SimCloudManager.java:210)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.setupTest(TestLargeCluster.java:108)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:968)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:748)
Caused by: java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
at java.util.concurrent.FutureTask.report(FutureTask.java:122)
at java.util.concurrent.FutureTask.get(FutureTask.java:192)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:619)
... 41 more
Caused by: java.io.IOException: java.lang.NullPointerException
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:679)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$15(SimCloudManager.java:618)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
... 1 more
Caused by: java.lang.NullPointerException
at org.apache.solr.client.solrj.cloud.autoscaling.Preference.compare(Preference.java:72)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy.setApproxValuesAndSortNodes(Policy.java:390)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy$Session.applyRules(Policy.java:315)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy$Session.<init>(Policy.java:277)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy.createSession(Policy.java:413)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:663)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.setAutoScalingConfig(AutoScalingHandler.java:650)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:215)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:669)
... 6 more
FAILED: org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testNodeLost
Error Message:
trigger did not fire within timeout, waitFor=5, killDelay=5000, minIgnored=0
Stack Trace:
java.lang.AssertionError: trigger did not fire within timeout, waitFor=5, killDelay=5000, minIgnored=0
at __randomizedtesting.SeedInfo.seed([828C65886443FEE9:3D99AB76E7A99B6F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.doTestNodeLost(TestLargeCluster.java:440)
at org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testNodeLost(TestLargeCluster.java:323)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:748)
FAILED: org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testNodeLost
Error Message:
java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
Stack Trace:
java.io.IOException: java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
at __randomizedtesting.SeedInfo.seed([828C65886443FEE9:3D99AB76E7A99B6F]:0)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:621)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager$1.request(SimCloudManager.java:210)
at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
at org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.setupTest(TestLargeCluster.java:108)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:968)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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:748)
Caused by: java.util.concurrent.ExecutionException: java.io.IOException: java.lang.NullPointerException
at java.util.concurrent.FutureTask.report(FutureTask.java:122)
at java.util.concurrent.FutureTask.get(FutureTask.java:192)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.request(SimCloudManager.java:619)
... 41 more
Caused by: java.io.IOException: java.lang.NullPointerException
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:679)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.lambda$request$15(SimCloudManager.java:618)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
... 1 more
Caused by: java.lang.NullPointerException
at org.apache.solr.client.solrj.cloud.autoscaling.Preference.compare(Preference.java:72)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy.setApproxValuesAndSortNodes(Policy.java:390)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy$Session.applyRules(Policy.java:315)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy$Session.<init>(Policy.java:277)
at org.apache.solr.client.solrj.cloud.autoscaling.Policy.createSession(Policy.java:413)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.verifyAutoScalingConf(AutoScalingHandler.java:663)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.setAutoScalingConfig(AutoScalingHandler.java:650)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.processOps(AutoScalingHandler.java:215)
at org.apache.solr.cloud.autoscaling.AutoScalingHandler.handleRequestBody(AutoScalingHandler.java:148)
at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:199)
at org.apache.solr.cloud.autoscaling.sim.SimCloudManager.simHandleSolrRequest(SimCloudManager.java:669)
... 6 more
Build Log:
[...truncated 14423 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestLargeCluster
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestLargeCluster_828C65886443FEE9-001/init-core-data-001
[junit4] 2> 2420045 INFO (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2420046 INFO (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-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> 2420046 INFO (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2420048 DEBUG (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10031_solr
[junit4] 2> 2420048 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 2420049 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 0
[junit4] 2> 2420049 WARN (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler Missing .system, keeping metrics history in memory
[junit4] 2> 2420049 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion -1
[junit4] 2> 2420049 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 0
[junit4] 2> 2420049 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10031_solr, 127.0.0.1:10034_solr, 127.0.0.1:10033_solr, 127.0.0.1:10037_solr, 127.0.0.1:10035_solr, 127.0.0.1:10032_solr, 127.0.0.1:10036_solr]
[junit4] 2> 2420049 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2420049 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 0, lastZnodeVersion 0
[junit4] 2> 2420049 DEBUG (ScheduledTrigger-9946-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 9
[junit4] 2> 2420057 INFO (TEST-TestLargeCluster.testAddNode-seed#[828C65886443FEE9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddNode
[junit4] 2> 2420057 DEBUG (TEST-TestLargeCluster.testAddNode-seed#[828C65886443FEE9]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 2420057 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 2420058 INFO (TEST-TestLargeCluster.testAddNode-seed#[828C65886443FEE9]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 2420058 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 2420058 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2420059 DEBUG (TEST-TestLargeCluster.testAddNode-seed#[828C65886443FEE9]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 2420059 DEBUG (TEST-TestLargeCluster.testAddNode-seed#[828C65886443FEE9]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 2420059 DEBUG (TEST-TestLargeCluster.testAddNode-seed#[828C65886443FEE9]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 2420059 DEBUG (TEST-TestLargeCluster.testAddNode-seed#[828C65886443FEE9]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 2420059 DEBUG (TEST-TestLargeCluster.testAddNode-seed#[828C65886443FEE9]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 2420059 DEBUG (TEST-TestLargeCluster.testAddNode-seed#[828C65886443FEE9]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 2420059 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220) ~[?:1.8.0_172]
[junit4] 2> at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:335) ~[?:1.8.0_172]
[junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:177) [java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_172]
[junit4] 2> 2420059 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 2420060 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 2420060 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion -1
[junit4] 2> 2420060 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 2420060 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10082_solr, 127.0.0.1:10118_solr, 127.0.0.1:10049_solr, 127.0.0.1:10104_solr, 127.0.0.1:10065_solr, 127.0.0.1:10120_solr, 127.0.0.1:10066_solr, 127.0.0.1:10121_solr, 127.0.0.1:10035_solr, 127.0.0.1:10051_solr, 127.0.0.1:10096_solr, 127.0.0.1:10119_solr, 127.0.0.1:10033_solr, 127.0.0.1:10105_solr, 127.0.0.1:10047_solr, 127.0.0.1:10102_solr, 127.0.0.1:10095_solr, 127.0.0.1:10081_solr, 127.0.0.1:10084_solr, 127.0.0.1:10036_solr, 127.0.0.1:10052_solr, 127.0.0.1:10079_solr, 127.0.0.1:10098_solr, 127.0.0.1:10054_solr, 127.0.0.1:10129_solr, 127.0.0.1:10099_solr, 127.0.0.1:10101_solr, 127.0.0.1:10123_solr, 127.0.0.1:10071_solr, 127.0.0.1:10076_solr, 127.0.0.1:10107_solr, 127.0.0.1:10055_solr, 127.0.0.1:10124_solr, 127.0.0.1:10070_solr, 127.0.0.1:10077_solr, 127.0.0.1:10038_solr, 127.0.0.1:10032_solr, 127.0.0.1:10108_solr, 127.0.0.1:10093_solr, 127.0.0.1:10067_solr, 127.0.0.1:10061_solr, 127.0.0.1:10130_solr, 127.0.0.1:10086_solr, 127.0.0.1:10089_solr, 127.0.0.1:10045_solr, 127.0.0.1:10042_solr, 127.0.0.1:10117_solr, 127.0.0.1:10083_solr, 127.0.0.1:10114_solr, 127.0.0.1:10080_solr, 127.0.0.1:10048_solr, 127.0.0.1:10064_solr, 127.0.0.1:10111_solr, 127.0.0.1:10043_solr, 127.0.0.1:10057_solr, 127.0.0.1:10112_solr, 127.0.0.1:10074_solr, 127.0.0.1:10126_solr, 127.0.0.1:10060_solr, 127.0.0.1:10073_solr, 127.0.0.1:10127_solr, 127.0.0.1:10113_solr, 127.0.0.1:10058_solr, 127.0.0.1:10090_solr, 127.0.0.1:10088_solr, 127.0.0.1:10039_solr, 127.0.0.1:10044_solr, 127.0.0.1:10092_solr, 127.0.0.1:10041_solr, 127.0.0.1:10087_solr, 127.0.0.1:10040_solr, 127.0.0.1:10085_solr, 127.0.0.1:10046_solr, 127.0.0.1:10068_solr, 127.0.0.1:10063_solr, 127.0.0.1:10115_solr, 127.0.0.1:10110_solr, 127.0.0.1:10069_solr, 127.0.0.1:10062_solr, 127.0.0.1:10116_solr, 127.0.0.1:10122_solr, 127.0.0.1:10050_solr, 127.0.0.1:10075_solr, 127.0.0.1:10125_solr, 127.0.0.1:10078_solr, 127.0.0.1:10128_solr, 127.0.0.1:10097_solr, 127.0.0.1:10031_solr, 127.0.0.1:10034_solr, 127.0.0.1:10091_solr, 127.0.0.1:10094_solr, 127.0.0.1:10106_solr, 127.0.0.1:10100_solr, 127.0.0.1:10037_solr, 127.0.0.1:10109_solr, 127.0.0.1:10053_solr, 127.0.0.1:10059_solr, 127.0.0.1:10103_solr, 127.0.0.1:10072_solr, 127.0.0.1:10056_solr]
[junit4] 2> 2420060 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420060 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2420060 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 2420081 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420101 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420121 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420142 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420162 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420162 DEBUG (simCloudManagerPool-9950-thread-1) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2420162 DEBUG (simCloudManagerPool-9950-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 2420163 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 2420163 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 2420163 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 2420165 DEBUG (simCloudManagerPool-9950-thread-2) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 2420165 DEBUG (simCloudManagerPool-9950-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 2420165 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 2420165 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger - Initial livenodes: [127.0.0.1:10082_solr, 127.0.0.1:10118_solr, 127.0.0.1:10049_solr, 127.0.0.1:10104_solr, 127.0.0.1:10065_solr, 127.0.0.1:10120_solr, 127.0.0.1:10066_solr, 127.0.0.1:10121_solr, 127.0.0.1:10035_solr, 127.0.0.1:10051_solr, 127.0.0.1:10096_solr, 127.0.0.1:10119_solr, 127.0.0.1:10033_solr, 127.0.0.1:10105_solr, 127.0.0.1:10047_solr, 127.0.0.1:10102_solr, 127.0.0.1:10095_solr, 127.0.0.1:10081_solr, 127.0.0.1:10084_solr, 127.0.0.1:10036_solr, 127.0.0.1:10052_solr, 127.0.0.1:10079_solr, 127.0.0.1:10098_solr, 127.0.0.1:10054_solr, 127.0.0.1:10129_solr, 127.0.0.1:10099_solr, 127.0.0.1:10101_solr, 127.0.0.1:10123_solr, 127.0.0.1:10071_solr, 127.0.0.1:10076_solr, 127.0.0.1:10107_solr, 127.0.0.1:10055_solr, 127.0.0.1:10124_solr, 127.0.0.1:10070_solr, 127.0.0.1:10077_solr, 127.0.0.1:10038_solr, 127.0.0.1:10032_solr, 127.0.0.1:10108_solr, 127.0.0.1:10093_solr, 127.0.0.1:10067_solr, 127.0.0.1:10061_solr, 127.0.0.1:10130_solr, 127.0.0.1:10086_solr, 127.0.0.1:10089_solr, 127.0.0.1:10045_solr, 127.0.0.1:10042_solr, 127.0.0.1:10117_solr, 127.0.0.1:10083_solr, 127.0.0.1:10114_solr, 127.0.0.1:10080_solr, 127.0.0.1:10048_solr, 127.0.0.1:10064_solr, 127.0.0.1:10111_solr, 127.0.0.1:10043_solr, 127.0.0.1:10057_solr, 127.0.0.1:10112_solr, 127.0.0.1:10074_solr, 127.0.0.1:10126_solr, 127.0.0.1:10060_solr, 127.0.0.1:10073_solr, 127.0.0.1:10127_solr, 127.0.0.1:10113_solr, 127.0.0.1:10058_solr, 127.0.0.1:10090_solr, 127.0.0.1:10088_solr, 127.0.0.1:10039_solr, 127.0.0.1:10044_solr, 127.0.0.1:10092_solr, 127.0.0.1:10041_solr, 127.0.0.1:10087_solr, 127.0.0.1:10040_solr, 127.0.0.1:10085_solr, 127.0.0.1:10046_solr, 127.0.0.1:10068_solr, 127.0.0.1:10063_solr, 127.0.0.1:10115_solr, 127.0.0.1:10110_solr, 127.0.0.1:10069_solr, 127.0.0.1:10062_solr, 127.0.0.1:10116_solr, 127.0.0.1:10122_solr, 127.0.0.1:10050_solr, 127.0.0.1:10075_solr, 127.0.0.1:10125_solr, 127.0.0.1:10078_solr, 127.0.0.1:10128_solr, 127.0.0.1:10097_solr, 127.0.0.1:10031_solr, 127.0.0.1:10034_solr, 127.0.0.1:10091_solr, 127.0.0.1:10094_solr, 127.0.0.1:10106_solr, 127.0.0.1:10100_solr, 127.0.0.1:10037_solr, 127.0.0.1:10109_solr, 127.0.0.1:10053_solr, 127.0.0.1:10059_solr, 127.0.0.1:10103_solr, 127.0.0.1:10072_solr, 127.0.0.1:10056_solr]
[junit4] 2> 2420165 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=5, actions=[{name=compute, class=org.apache.solr.cloud.autoscaling.ComputePlanAction}, {name=execute, class=org.apache.solr.cloud.autoscaling.ExecutePlanAction}, {name=test, class=org.apache.solr.cloud.autoscaling.sim.TestLargeCluster$TestTriggerAction}], enabled=true}
[junit4] 2> 2420165 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 2420169 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420169 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420182 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420190 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420190 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420202 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420210 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420210 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420222 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420230 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420230 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420243 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420250 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420250 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420263 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420271 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420271 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420283 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420291 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420291 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420303 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420311 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420311 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420324 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420331 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420331 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420344 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420352 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420352 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420364 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420372 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420372 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420385 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420392 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420392 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420405 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420412 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420413 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420425 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420433 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420433 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420445 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420453 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420453 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420466 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420473 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420473 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420486 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420494 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420494 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420506 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420514 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420514 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420526 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420534 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420534 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420547 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420554 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420555 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420567 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420575 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420575 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420587 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420595 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420595 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420607 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420615 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420615 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420628 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420636 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420636 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420648 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420656 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420656 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420668 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420676 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420676 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420688 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420696 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420696 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420709 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420717 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420717 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420729 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420737 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420737 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420749 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420757 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420757 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420769 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420777 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420777 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420790 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420798 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420798 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420810 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420818 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420818 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420830 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420838 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420838 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420850 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420859 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420859 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420871 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420879 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420879 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420891 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420899 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420899 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420911 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420919 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420920 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420931 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420940 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420940 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420952 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420960 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420960 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420972 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2420980 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2420980 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2420992 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421001 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421001 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421013 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421021 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421021 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421033 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421041 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421041 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421053 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421061 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421061 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421073 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421082 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421082 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421094 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421102 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421102 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421114 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421122 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421122 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421134 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421142 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421143 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421154 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421163 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421163 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421175 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421183 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421183 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421195 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421203 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421203 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421215 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421224 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421224 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421235 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421244 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421244 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421256 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421264 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421264 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421276 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421284 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421284 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421296 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421305 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421305 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421316 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421325 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421325 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421337 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421345 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421345 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421357 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421366 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421366 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421377 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421386 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421386 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421397 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421406 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421406 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421418 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421426 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421426 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421438 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421447 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421447 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421458 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421467 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421467 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421478 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421487 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421487 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421499 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421507 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421507 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421519 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421528 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421528 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421539 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421548 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421548 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421559 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421568 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421568 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421580 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421588 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421588 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421600 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421609 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421609 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421620 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421629 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421629 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421640 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421649 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421649 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421661 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421669 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421669 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421681 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421690 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421690 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421701 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421710 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421710 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421721 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421730 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421730 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421741 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421750 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421750 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421762 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421771 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421771 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421782 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421791 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421791 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421802 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421811 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421811 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421822 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421831 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421832 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421843 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421852 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421852 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421863 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421872 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421872 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421883 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421892 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421892 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421903 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421913 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421913 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421923 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421933 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421933 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421944 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421953 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421953 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421964 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421973 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421973 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2421984 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2421994 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2421994 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422004 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422014 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422014 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422025 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422034 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422034 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422045 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422054 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422054 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422065 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422075 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422075 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422085 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422095 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422095 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422106 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422115 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422115 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422126 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422135 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422135 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422146 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422156 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422156 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422166 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422176 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422176 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422187 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422196 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422196 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422207 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422217 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422217 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422227 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422237 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422237 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422247 DEBUG (ScheduledTrigger-9951-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422257 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422257 DEBUG (ScheduledTrigger-9951-thread-3) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422268 DEBUG (ScheduledTrigger-9951-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 100
[junit4] 2> 2422278 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 2422278 DEBUG (ScheduledTrigger-9951-thread-4) [ ] o.a.s.c.a.NodeAddedTrigger Found livenodes: 100
[junit4] 2> 2422288 DEBUG (ScheduledTrigger-9951-thread-2) [
[...truncated too long message...]
es:1minRate\":4,\n \"SEARCHER.searcher.numDocs\":0,\n \"shard\":\"shard1\",\n \"collection\":\"testSearchRate\"}}",
[junit4] 2> "{\"core_node1\":{\n \"core\":\"testSearchRate_shard1_replica_n1\",\n \"leader\":\"true\",\n \"SEARCHER.searcher.maxDoc\":0,\n \"SEARCHER.searcher.deletedDocs\":0,\n \"INDEX.sizeInBytes\":1,\n \"rate\":4.0,\n \"node_name\":\"127.0.0.1:10264_solr\",\n \"state\":\"active\",\n \"type\":\"NRT\",\n \"QUERY./select.requestTimes:1minRate\":4,\n \"SEARCHER.searcher.numDocs\":0,\n \"shard\":\"shard1\",\n \"collection\":\"testSearchRate\"}}"],
[junit4] 2> "__start__":3,
[junit4] 2> "coldNodes":{},
[junit4] 2> "coldShards":{},
[junit4] 2> "coldReplicas":[],
[junit4] 2> "coldCollections":{},
[junit4] 2> "hotShards":{"testSearchRate":{"shard1":40.0}},
[junit4] 2> "hotCollections":{"testSearchRate":40.0},
[junit4] 2> "hotNodes":{
[junit4] 2> "127.0.0.1:10187_solr":4.0,
[junit4] 2> "127.0.0.1:10281_solr":4.0,
[junit4] 2> "127.0.0.1:10263_solr":4.0,
[junit4] 2> "127.0.0.1:10286_solr":4.0,
[junit4] 2> "127.0.0.1:10217_solr":4.0,
[junit4] 2> "127.0.0.1:10192_solr":4.0,
[junit4] 2> "127.0.0.1:10240_solr":4.0,
[junit4] 2> "127.0.0.1:10152_solr":4.0,
[junit4] 2> "127.0.0.1:10264_solr":4.0,
[junit4] 2> "127.0.0.1:10168_solr":4.0},
[junit4] 2> "_enqueue_time_":1271445631523964,
[junit4] 2> "requestedOps":[
[junit4] 2> "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n \"first\":\"testSearchRate\",\n \"second\":\"shard1\"}]}}",
[junit4] 2> "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n \"first\":\"testSearchRate\",\n \"second\":\"shard1\"}]}}",
[junit4] 2> "Op{action=ADDREPLICA, hints={COLL_SHARD=[{\n \"first\":\"testSearchRate\",\n \"second\":\"shard1\"}]}}"]}} with context properties: {operations=[org.apache.solr.client.solrj.request.CollectionAdminRequest$AddReplica@6beb944c, org.apache.solr.client.solrj.request.CollectionAdminRequest$AddReplica@4471f0f8, org.apache.solr.client.solrj.request.CollectionAdminRequest$AddReplica@5a4afb27], AFTER_ACTION=[compute], BEFORE_ACTION=[compute, execute]}
[junit4] 2> 63369 DEBUG (AutoscalingActionExecutor-36-thread-1) [ ] o.a.s.c.a.ExecutePlanAction Executing operation: action=ADDREPLICA&collection=testSearchRate&shard=shard1&node=127.0.0.1:10248_solr&type=NRT
[junit4] 2> 63370 INFO (simCloudManagerPool-34-thread-30) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10248_solr for creating new replica
[junit4] 2> 63381 DEBUG (AutoscalingActionExecutor-36-thread-1) [ ] o.a.s.c.a.ExecutePlanAction Executing operation: action=ADDREPLICA&collection=testSearchRate&shard=shard1&node=127.0.0.1:10267_solr&type=NRT
[junit4] 2> 63381 INFO (simCloudManagerPool-34-thread-35) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10267_solr for creating new replica
[junit4] 2> 63392 DEBUG (AutoscalingActionExecutor-36-thread-1) [ ] o.a.s.c.a.ExecutePlanAction Executing operation: action=ADDREPLICA&collection=testSearchRate&shard=shard1&node=127.0.0.1:10280_solr&type=NRT
[junit4] 2> 63393 INFO (simCloudManagerPool-34-thread-40) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10280_solr for creating new replica
[junit4] 2> 63405 DEBUG (AutoscalingActionExecutor-36-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 63405 DEBUG (AutoscalingActionExecutor-36-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: search_rate_trigger after 100ms
[junit4] 2> 63405 DEBUG (AutoscalingActionExecutor-36-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 2658 ms for event id=4845e4ac0f832T2mfamekteigsvj0mqex9zx9kt
[junit4] 2> 63444 INFO (TEST-TestLargeCluster.testSearchRate-seed#[828C65886443FEE9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testSearchRate
[junit4] 2> 63444 INFO (TEST-TestLargeCluster.testSearchRate-seed#[828C65886443FEE9]) [ ] o.a.s.c.a.s.SimSolrCloudTestCase #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 100
[junit4] 2> ## Empty nodes: 76
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections: [.system, testSearchRate]
[junit4] 2> ## Max replicas per node: 1
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 24
[junit4] 2> ## * .system 1
[junit4] 2> ## - active 1
[junit4] 2> ## * testSearchRate 23
[junit4] 2> ## - active 23
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ## - ADDREPLICA 3
[junit4] 2> ## - CREATE 1
[junit4] 2> ## - DELETESTATUS 3
[junit4] 2> ## - REQUESTSTATUS 3
[junit4] 2> ## - autoscaling 3
[junit4] 2> ## - update 2
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2> ## * Trigger: search_rate_trigger
[junit4] 2> ## - STARTED 1
[junit4] 2> ## - SUCCEEDED 1
[junit4] 2>
[junit4] OK 0.57s J1 | TestLargeCluster.testSearchRate
[junit4] 2> 63445 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 63445 DEBUG (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 63445 DEBUG (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 63445 DEBUG (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 63445 DEBUG (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 63445 DEBUG (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 63445 DEBUG (SUITE-TestLargeCluster-seed#[828C65886443FEE9]-worker) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.sim.TestLargeCluster_828C65886443FEE9-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=377, maxMBSortInHeap=7.526715874207602, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@1bfaf43a), locale=lt, timezone=Europe/Zaporozhye
[junit4] 2> NOTE: Linux 4.13.0-41-generic amd64/Oracle Corporation 1.8.0_172 (64-bit)/cpus=8,threads=1,free=234197536,total=396361728
[junit4] 2> NOTE: All tests run in this JVM: [TestLargeCluster, TestLargeCluster]
[junit4] Completed [5/5 (2!)] on J1 in 24.63s, 4 tests, 1 failure <<< FAILURES!
[junit4]
[junit4]
[junit4] Tests with failures [seed: 828C65886443FEE9]:
[junit4] - org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testNodeLost
[junit4] - org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testSearchRate
[junit4] - org.apache.solr.cloud.autoscaling.sim.TestLargeCluster.testNodeLost
[junit4]
[junit4]
[junit4] JVM J0: 0.43 .. 40.76 = 40.33s
[junit4] JVM J1: 0.44 .. 64.12 = 63.68s
[junit4] JVM J2: 0.46 .. 56.44 = 55.98s
[junit4] Execution time total: 1 minute 4 seconds
[junit4] Tests summary: 5 suites, 20 tests, 2 errors, 1 failure
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1092: There were test failures: 5 suites, 20 tests, 2 errors, 1 failure [seed: 828C65886443FEE9]
Total time: 1 minute 5 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 2/5 failed: org.apache.solr.cloud.autoscaling.sim.TestLargeCluster
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=3dc4fa199c175ed6351f66bac5c23c73b4e3f89a, workspace=/var/lib/jenkins/workspace/Lucene-Solr-BadApples-master-Linux
[WARNINGS] Computing warning deltas based on reference build #44
Recording test results
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[JENKINS-EA] Lucene-Solr-BadApples-master-Linux
(64bit/jdk-11-ea+14) - Build # 46 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-master-Linux/46/
Java: 64bit/jdk-11-ea+14 -XX:+UseCompressedOops -XX:+UseParallelGC
7 tests failed.
FAILED: org.apache.solr.handler.tagger.Tagger2Test.testStopWords
Error Message:
[]: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
Stack Trace:
java.lang.AssertionError: []: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:C89B6898C42C363A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.tagger.TaggerTestCase.assertSortedArrayEquals(TaggerTestCase.java:199)
at org.apache.solr.handler.tagger.TaggerTestCase.assertTags(TaggerTestCase.java:132)
at org.apache.solr.handler.tagger.Tagger2Test.testStopWords(Tagger2Test.java:102)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:47)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 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.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.handler.tagger.Tagger2Test.testStopWords
Error Message:
[]: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
Stack Trace:
java.lang.AssertionError: []: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:C89B6898C42C363A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.tagger.TaggerTestCase.assertSortedArrayEquals(TaggerTestCase.java:199)
at org.apache.solr.handler.tagger.TaggerTestCase.assertTags(TaggerTestCase.java:132)
at org.apache.solr.handler.tagger.Tagger2Test.testStopWords(Tagger2Test.java:102)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:47)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 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.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.handler.tagger.Tagger2Test.testStopWords
Error Message:
[]: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
Stack Trace:
java.lang.AssertionError: []: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:C89B6898C42C363A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.tagger.TaggerTestCase.assertSortedArrayEquals(TaggerTestCase.java:199)
at org.apache.solr.handler.tagger.TaggerTestCase.assertTags(TaggerTestCase.java:132)
at org.apache.solr.handler.tagger.Tagger2Test.testStopWords(Tagger2Test.java:102)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:47)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 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.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.handler.tagger.Tagger2Test.testStopWords
Error Message:
[]: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
Stack Trace:
java.lang.AssertionError: []: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:C89B6898C42C363A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.tagger.TaggerTestCase.assertSortedArrayEquals(TaggerTestCase.java:199)
at org.apache.solr.handler.tagger.TaggerTestCase.assertTags(TaggerTestCase.java:132)
at org.apache.solr.handler.tagger.Tagger2Test.testStopWords(Tagger2Test.java:102)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:47)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 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.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.handler.tagger.Tagger2Test.testStopWords
Error Message:
[]: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
Stack Trace:
java.lang.AssertionError: []: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:C89B6898C42C363A]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.tagger.TaggerTestCase.assertSortedArrayEquals(TaggerTestCase.java:199)
at org.apache.solr.handler.tagger.TaggerTestCase.assertTags(TaggerTestCase.java:132)
at org.apache.solr.handler.tagger.Tagger2Test.testStopWords(Tagger2Test.java:102)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.junit.rules.TestWatcher$1.evaluate(TestWatcher.java:47)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 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.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.update.TransactionLogTest.testBigLastAddSize
Error Message:
For input string: "00000000000000000009911634398752770448"
Stack Trace:
java.lang.NumberFormatException: For input string: "00000000000000000009911634398752770448"
at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:539249EBB446E3C8]:0)
at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
at java.base/java.lang.Long.parseLong(Long.java:692)
at java.base/java.lang.Long.parseLong(Long.java:817)
at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:153)
at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:141)
at org.apache.solr.update.TransactionLogTest.testBigLastAddSize(TransactionLogTest.java:34)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 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.base/java.lang.Thread.run(Thread.java:832)
FAILED: org.apache.solr.update.TransactionLogTest.testBigLastAddSize
Error Message:
For input string: "000000000000000000013740335325899003270"
Stack Trace:
java.lang.NumberFormatException: For input string: "000000000000000000013740335325899003270"
at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:539249EBB446E3C8]:0)
at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
at java.base/java.lang.Long.parseLong(Long.java:692)
at java.base/java.lang.Long.parseLong(Long.java:817)
at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:153)
at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:141)
at org.apache.solr.update.TransactionLogTest.testBigLastAddSize(TransactionLogTest.java:34)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
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 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.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 12820 lines...]
[junit4] Suite: org.apache.solr.handler.tagger.Tagger2Test
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.tagger.Tagger2Test_4B68E7405D081FC9-001/init-core-data-001
[junit4] 2> 550583 WARN (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=3 numCloses=3
[junit4] 2> 550583 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 550584 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 550584 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 550584 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 550584 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 550592 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 550596 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=minimal
[junit4] 2> 550601 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.h.t.WordLengthTaggingFilterFactory set minLength=4
[junit4] 2> 550603 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.6 with uniqueid field id
[junit4] 2> 550667 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e8a1687
[junit4] 2> 550671 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e8a1687
[junit4] 2> 550671 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e8a1687
[junit4] 2> 550672 INFO (coreLoadExecutor-1570-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 550672 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 550684 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 550694 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 550697 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.h.t.WordLengthTaggingFilterFactory set minLength=4
[junit4] 2> 550699 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema minimal/1.6 with uniqueid field id
[junit4] 2> 550699 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 550699 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@4e8a1687
[junit4] 2> 550700 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 550700 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.tagger.Tagger2Test_4B68E7405D081FC9-001/init-core-data-001/]
[junit4] 2> 550742 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 550742 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 550743 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5b08b9f9[collection1] main]
[junit4] 2> 550743 WARN (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 550743 INFO (coreLoadExecutor-1570-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 550743 INFO (searcherExecutor-1571-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@5b08b9f9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 550744 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 550744 INFO (searcherExecutor-1571-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_tag:[*+TO+*]&distrib=false&event=firstSearcher} hits=0 status=0 QTime=0
[junit4] 2> 550744 INFO (searcherExecutor-1571-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 550745 INFO (searcherExecutor-1571-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5b08b9f9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 550746 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.h.t.TaggerTestCase testWDF(org.apache.solr.handler.tagger.Tagger2Test) being run...
[junit4] 2> 550746 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWDF
[junit4] 2> 550746 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 550747 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 550752 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 5
[junit4] 2> 550754 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 550754 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 550754 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@31107171 commitCommandVersion:0
[junit4] 2> 550756 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@54430792[collection1] main]
[junit4] 2> 550757 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 550757 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@54430792[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C2)))}
[junit4] 2> 550757 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_tag:[*+TO+*]&distrib=false&event=newSearcher} hits=2 status=0 QTime=0
[junit4] 2> 550757 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 550758 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@54430792[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C2)))}
[junit4] 2> 550758 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 4
[junit4] 2> 550765 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=6
[junit4] 2> 550767 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 550767 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 550767 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 550768 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 550768 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 550768 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 550768 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 550768 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testWDF
[junit4] 2> 550769 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.h.t.TaggerTestCase testStopWords(org.apache.solr.handler.tagger.Tagger2Test) being run...
[junit4] 2> 550770 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testStopWords
[junit4] 2> 550770 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 550770 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 550771 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 550771 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 550771 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 550772 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@31107171 commitCommandVersion:0
[junit4] 2> 550773 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@5684774f[collection1] main]
[junit4] 2> 550774 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 550774 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@5684774f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C2)))}
[junit4] 2> 550774 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_tag:[*+TO+*]&distrib=false&event=newSearcher} hits=2 status=0 QTime=0
[junit4] 2> 550774 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 550775 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5684774f[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_1(8.0.0):C2)))}
[junit4] 2> 550775 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 550775 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagStop)}} status=0 QTime=0
[junit4] 2> 550776 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagStop)}} status=0 QTime=0
[junit4] 2> 550776 INFO (TEST-Tagger2Test.testStopWords-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testStopWords
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=Tagger2Test -Dtests.method=testStopWords -Dtests.seed=4B68E7405D081FC9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=qu-PE -Dtests.timezone=Europe/Isle_of_Man -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] FAILURE 0.01s J0 | Tagger2Test.testStopWords <<<
[junit4] > Throwable #1: java.lang.AssertionError: []: didn't find expected TestTag{[2-6] doc=1:'A City A' substr=City} (of 1); java.lang.AssertionError: array lengths differed, expected.length=1 actual.length=0
[junit4] > at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:C89B6898C42C363A]:0)
[junit4] > at org.apache.solr.handler.tagger.TaggerTestCase.assertSortedArrayEquals(TaggerTestCase.java:199)
[junit4] > at org.apache.solr.handler.tagger.TaggerTestCase.assertTags(TaggerTestCase.java:132)
[junit4] > at org.apache.solr.handler.tagger.Tagger2Test.testStopWords(Tagger2Test.java:102)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> 550778 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.h.t.TaggerTestCase testLongestDominantRight(org.apache.solr.handler.tagger.Tagger2Test) being run...
[junit4] 2> 550778 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLongestDominantRight
[junit4] 2> 550779 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 550779 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 550779 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 550780 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 0
[junit4] 2> 550780 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[2]} 0 0
[junit4] 2> 550781 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[3]} 0 0
[junit4] 2> 550782 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[4]} 0 0
[junit4] 2> 550783 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[5]} 0 0
[junit4] 2> 550783 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[6]} 0 0
[junit4] 2> 550784 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[7]} 0 0
[junit4] 2> 550785 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[8]} 0 0
[junit4] 2> 550786 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[9]} 0 0
[junit4] 2> 550786 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 550786 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@31107171 commitCommandVersion:0
[junit4] 2> 550790 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@23c078bc[collection1] main]
[junit4] 2> 550790 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 550790 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@23c078bc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C10)))}
[junit4] 2> 550791 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_tag:[*+TO+*]&distrib=false&event=newSearcher} hits=10 status=0 QTime=0
[junit4] 2> 550791 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 550792 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@23c078bc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C10)))}
[junit4] 2> 550792 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 5
[junit4] 2> 550793 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT)}} status=0 QTime=0
[junit4] 2> 550793 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT)}} status=0 QTime=0
[junit4] 2> 550794 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT)}} status=0 QTime=0
[junit4] 2> 550794 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLongestDominantRight
[junit4] 2> 550795 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.h.t.TaggerTestCase testMultibyteChar(org.apache.solr.handler.tagger.Tagger2Test) being run...
[junit4] 2> 550795 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMultibyteChar
[junit4] 2> 550801 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 550801 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 550802 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 0
[junit4] 2> 550802 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 550802 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@31107171 commitCommandVersion:0
[junit4] 2> 550804 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@2d51f57[collection1] main]
[junit4] 2> 550804 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 550804 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@2d51f57[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.0.0):C1)))}
[junit4] 2> 550804 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_tag:[*+TO+*]&distrib=false&event=newSearcher} hits=1 status=0 QTime=0
[junit4] 2> 550804 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 550805 INFO (searcherExecutor-1571-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@2d51f57[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.0.0):C1)))}
[junit4] 2> 550805 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 3
[junit4] 2> 550806 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT)}} status=0 QTime=0
[junit4] 2> 550807 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMultibyteChar
[junit4] 2> 550807 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 550807 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1989459602
[junit4] 2> 550807 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 550807 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4f2ab0b4: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@77b82818
[junit4] 2> 550811 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 550811 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2b02d9cd: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@1403d29
[junit4] 2> 550814 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 550814 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@12809f95: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@13884c01
[junit4] 2> 550818 INFO (coreCloseExecutor-1575-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@74bd37bc
[junit4] 2> 550819 INFO (coreCloseExecutor-1575-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=74bd37bc
[junit4] 2> 550819 INFO (coreCloseExecutor-1575-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1d455f14: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@6800f0ab
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.tagger.Tagger2Test_4B68E7405D081FC9-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=730, maxMBSortInHeap=7.437464412778227, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4a3fa23a), locale=qu-PE, timezone=Europe/Isle_of_Man
[junit4] 2> NOTE: Linux 4.13.0-41-generic amd64/Oracle Corporation 11-ea (64-bit)/cpus=8,threads=1,free=210123672,total=505413632
[junit4] 2> NOTE: All tests run in this JVM: [NodeAddedTriggerTest, ReplaceNodeTest, TestNumericRangeQuery32, HdfsDirectoryTest, TestStressCloudBlindAtomicUpdates, TestHdfsBackupRestoreCore, TestNumericTerms64, TestHighlightDedupGrouping, TestRawTransformer, CoreMergeIndexesAdminHandlerTest, HDFSCollectionsAPITest, SimpleFacetsTest, TestLeaderElectionWithEmptyReplica, ShufflingReplicaListTransformerTest, TestInPlaceUpdatesDistrib, SearchRateTriggerTest, DocExpirationUpdateProcessorFactoryTest, TestDynamicFieldCollectionResource, SolrXmlInZkTest, TermVectorComponentDistributedTest, RAMDirectoryFactoryTest, TestComponentsName, TestExecutePlanAction, NodeMarkersRegistrationTest, CustomHighlightComponentTest, CollectionPropsTest, DebugComponentTest, TestStandardQParsers, HdfsAutoAddReplicasIntegrationTest, TestIBSimilarityFactory, ChaosMonkeyNothingIsSafeTest, AssignTest, SolrRequestParserTest, CoreAdminCreateDiscoverTest, CurrencyFieldTypeTest, TestFieldSortValues, SegmentsInfoRequestHandlerTest, Tagger2Test]
[junit4] Completed [115/818 (1!)] on J0 in 0.26s, 5 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 2113 lines...]
[junit4] Suite: org.apache.solr.update.TransactionLogTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TransactionLogTest -Dtests.method=testBigLastAddSize -Dtests.seed=4B68E7405D081FC9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=az-Latn -Dtests.timezone=Etc/GMT-4 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J1 | TransactionLogTest.testBigLastAddSize <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: For input string: "000000000000000000013740335325899003270"
[junit4] > at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:539249EBB446E3C8]:0)
[junit4] > at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
[junit4] > at java.base/java.lang.Long.parseLong(Long.java:692)
[junit4] > at java.base/java.lang.Long.parseLong(Long.java:817)
[junit4] > at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:153)
[junit4] > at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:141)
[junit4] > at org.apache.solr.update.TransactionLogTest.testBigLastAddSize(TransactionLogTest.java:34)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5580db1c), locale=az-Latn, timezone=Etc/GMT-4
[junit4] 2> NOTE: Linux 4.13.0-41-generic amd64/Oracle Corporation 11-ea (64-bit)/cpus=8,threads=1,free=185614984,total=534773760
[junit4] 2> NOTE: All tests run in this JVM: [RestoreTriggerStateTest, SSLMigrationTest, SubstringBytesRefFilterTest, CdcrUpdateLogTest, TestStressInPlaceUpdates, CoreAdminOperationTest, PeerSyncWithIndexFingerprintCachingTest, TestTrie, IndexSchemaTest, TestConfigsApi, HdfsChaosMonkeySafeLeaderTest, TestSubQueryTransformer, TestLeaderElectionZkExpiry, EmbeddedSolrNoSerializeTest, FieldMutatingUpdateProcessorTest, TriggerIntegrationTest, UUIDUpdateProcessorFallbackTest, RequiredFieldsTest, SyncSliceTest, HighlighterMaxOffsetTest, CollectionStateFormat2Test, SolrCoreTest, TestRecoveryHdfs, TestNodeLostTrigger, ScheduledTriggerTest, DistributedMLTComponentTest, ConfigSetsAPITest, DistributedQueryComponentOptimizationTest, TestOrdValues, MetricTriggerTest, TestExactSharedStatsCache, CleanupOldIndexTest, CollectionsAPIDistributedZkTest, TestAnalyzeInfixSuggestions, SystemInfoHandlerTest, TestUseDocValuesAsStored2, TestSimpleQParserPlugin, DistributedFacetPivotLargeTest, UninvertDocValuesMergePolicyTest, TestSQLHandler, TestReplicationHandler, TermsComponentTest, ClassificationUpdateProcessorTest, BlockJoinFacetDistribTest, BasicFunctionalityTest, DocumentBuilderTest, TestDownShardTolerantSearch, BasicAuthIntegrationTest, TestHalfAndHalfDocValues, ComputePlanActionTest, ResponseBuilderTest, TestFilteredDocIdSet, TestLegacyFieldCache, TestZkChroot, SolrGangliaReporterTest, TestUninvertingReader, TestReload, TestSurroundQueryParser, AliasIntegrationTest, ResponseHeaderTest, TestComplexPhraseQParserPlugin, DistributedDebugComponentTest, DocValuesNotIndexedTest, TestMissingGroups, TestDFISimilarityFactory, TestPushWriter, TestReplicationHandlerBackup, TestStreamBody, CurrencyRangeFacetCloudTest, TestManagedResource, HdfsLockFactoryTest, TestSha256AuthenticationProvider, DOMUtilTest, TestElisionMultitermQuery, HdfsRecoveryZkTest, TestAuthenticationFramework, SimplePostToolTest, SignatureUpdateProcessorFactoryTest, TestLocalFSCloudBackupRestore, OverseerModifyCollectionTest, ConnectionReuseTest, TestRandomRequestDistribution, TaggerTest, DirectUpdateHandlerOptimizeTest, OutOfBoxZkACLAndCredentialsProvidersTest, QueryElevationComponentTest, TestRTGBase, TestStressVersions, TestFieldCache, TestCloudSearcherWarming, TestPostingsSolrHighlighter, SolrShardReporterTest, TestComputePlanAction, TestRandomCollapseQParserPlugin, MergeStrategyTest, TestSimpleTrackingShardHandler, TestRecovery, NodeLostTriggerIntegrationTest, TestSolrConfigHandlerConcurrent, FastVectorHighlighterTest, TestFreeTextSuggestions, TestOverriddenPrefixQueryForCustomFieldType, CdcrRequestHandlerTest, TestXIncludeConfig, TestSolrIndexConfig, TestSerializedLuceneMatchVersion, InfixSuggestersTest, TestImplicitCoreProperties, CdcrBootstrapTest, TestSolrCoreParser, InfoHandlerTest, DistributedIntervalFacetingTest, TestDeleteCollectionOnDownNodes, GraphQueryTest, TestCloudInspectUtil, CoreSorterTest, BasicZkTest, TestConfigReload, SOLR749Test, OrderedExecutorTest, TestRetrieveFieldsOptimizer, PropertiesRequestHandlerTest, SoftAutoCommitTest, TestSizeLimitedDistributedMap, TestFieldCacheSortRandom, HdfsDirectoryFactoryTest, TestConfigSetsAPIExclusivity, TestReversedWildcardFilterFactory, MultiSolrCloudTestCaseTest, TestSSLRandomization, TestStressUserVersions, TestSolrCloudWithHadoopAuthPlugin, TestPivotHelperCode, TestFieldTypeCollectionResource, TestSimpleTextCodec, JsonLoaderTest, UnloadDistributedZkTest, FullSolrCloudDistribCmdsTest, ZkSolrClientTest, TestDistributedGrouping, ZkControllerTest, TestStressReorder, TestJoin, DistributedTermsComponentTest, SolrCmdDistributorTest, BadIndexSchemaTest, ConvertedLegacyTest, TestSort, TestFiltering, TestBadConfig, ShowFileRequestHandlerTest, SpellCheckCollatorTest, TestFoldingMultitermQuery, SuggesterTest, SpatialFilterTest, PolyFieldTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, TestWordDelimiterFilterFactory, DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, DistanceFunctionTest, XsltUpdateRequestHandlerTest, TestQueryUtils, DirectSolrSpellCheckerTest, TestOmitPositions, RequestHandlersTest, TestIndexingPerformance, IndexSchemaRuntimeFieldTest, SolrPluginUtilsTest, TestCollationField, UpdateRequestProcessorFactoryTest, QueryParsingTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, PingRequestHandlerTest, CSVRequestHandlerTest, HighlighterConfigTest, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, TestQuerySenderNoQuery, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestStressRecovery, TestMergePolicyConfig, MultiTermTest, SampleTest, TestSearchPerf, MinimalSchemaTest, OutputWriterTest, TestFuzzyAnalyzedSuggestions, DirectSolrConnectionTest, TestLuceneMatchVersion, TestCharFilters, SynonymTokenizerTest, TestSweetSpotSimilarityFactory, TestPerFieldSimilarity, TestFastWriter, ScriptEngineTest, URLClassifyProcessorTest, DateMathParserTest, TestSystemIdResolver, TestSuggestSpellingConverter, ClusterStateTest, TestUtils, ZkNodePropsTest, TestCursorMarkWithoutUniqueKey, TestLuceneIndexBackCompat, ChaosMonkeyNothingIsSafeWithPullReplicasTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DeleteStatusTest, DistributedVersionInfoTest, HealthCheckHandlerTest, HttpPartitionOnCommitTest, LIROnShardRestartTest, LIRRollingUpdatesTest, MetricsHistoryIntegrationTest, NodeMutatorTest, OverseerCollectionConfigSetProcessorTest, OverseerRolesTest, OverseerStatusTest, OverseerTaskQueueTest, RecoveryAfterSoftCommitTest, RemoteQueryErrorTest, ReplicationFactorTest, TestCloudPivotFacet, TestClusterProperties, TestConfigSetsAPIZkFailure, TestExclusionRuleCollectionAccess, TestLockTree, TestMiniSolrCloudClusterSSL, TestPullReplica, TestRebalanceLeaders, TestSegmentSorting, TestSolrCloudWithDelegationTokens, TestStressLiveNodes, TestTolerantUpdateProcessorCloud, TlogReplayBufferedWhileIndexingTest, VMParamsZkACLAndCredentialsProvidersTest, ZkFailoverTest, CustomCollectionTest, HdfsCollectionsAPIDistributedZkTest, TestCollectionAPI, TestReplicaProperties, AutoAddReplicasPlanActionTest, AutoScalingHandlerTest, ExecutePlanActionTest, HttpTriggerListenerTest, IndexSizeTriggerTest, TestNodeAddedTrigger, TestPolicyCloud, TestTriggerIntegration, BaseCdcrDistributedZkTest, CdcrReplicationHandlerTest, HdfsBasicDistributedZk2Test, HdfsBasicDistributedZkTest, HdfsRecoverLeaseTest, HdfsTlogReplayBufferedWhileIndexingTest, HdfsUnloadDistributedZkTest, HdfsWriteToMultipleCollectionsTest, TestClusterStateMutator, ZkStateWriterTest, TestNRTOpen, TestSolrCoreSnapshots, SearchHandlerTest, TestBlobHandler, TestSystemCollAutoCreate, StatsReloadRaceTest, DistributedQueryComponentCustomSortTest, SpatialHeatmapFacetsTest, TestHttpShardHandlerFactory, TestTrackingShardHandlerFactory, RandomizedTaggerTest, TaggingAttributeTest, XmlInterpolationTest, TestSlowCompositeReaderWrapper, CheckHdfsIndexTest, TestMacros, JSONWriterTest, TestBinaryResponseWriter, TestExportWriter, TestSubQueryTransformerCrossCore, TestManagedResourceStorage, TestRestManager, TestBulkSchemaAPI, TestDynamicFieldResource, TestFieldCollectionResource, TestSchemaNameResource, TestSchemaSimilarityResource, TestSchemaVersionResource, TestManagedSynonymGraphFilterFactory, BadCopyFieldTest, ChangedSchemaMergeTest, DocValuesMissingTest, ManagedSchemaRoundRobinCloudTest, TestCloudManagedSchema, TestManagedSchema, TestManagedSchemaAPI, TestManagedSchemaThreadSafety, TestSchemaManager, TestSchemalessBufferedUpdates, TestSortableTextField, TestAddFieldRealTimeGet, TestInitQParser, TestMaxScoreQueryParser, TestReloadDeadlock, TestSolr4Spatial2, TestTrieFacet, TestJsonFacetRefinement, TestJsonFacets, TestJsonRequest, CloudMLTQParserTest, TestAuthorizationFramework, TestNumericTerms32, SolrIndexMetricsTest, TestExceedMaxTermLength, TestInPlaceUpdatesStandalone, TransactionLogTest]
[junit4] Completed [756/818 (2!)] on J1 in 0.01s, 1 test, 1 error <<< FAILURES!
[...truncated 43952 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-BadApples-master-Linux/46/consoleText
[repro] Revision: 124b5e047df697bfd61f1d14f87a0ee5d24f68d8
[repro] Repro line: ant test -Dtestcase=Tagger2Test -Dtests.method=testStopWords -Dtests.seed=4B68E7405D081FC9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=qu-PE -Dtests.timezone=Europe/Isle_of_Man -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] Repro line: ant test -Dtestcase=TransactionLogTest -Dtests.method=testBigLastAddSize -Dtests.seed=4B68E7405D081FC9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=az-Latn -Dtests.timezone=Etc/GMT-4 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/core
[repro] TransactionLogTest
[repro] Tagger2Test
[repro] ant compile-test
[...truncated 3305 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=10 -Dtests.class="*.TransactionLogTest|*.Tagger2Test" -Dtests.showOutput=onerror -Dtests.seed=4B68E7405D081FC9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=az-Latn -Dtests.timezone=Etc/GMT-4 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 36 lines...]
[junit4] Suite: org.apache.solr.update.TransactionLogTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TransactionLogTest -Dtests.method=testBigLastAddSize -Dtests.seed=4B68E7405D081FC9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=az-Latn -Dtests.timezone=Etc/GMT-4 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.02s J2 | TransactionLogTest.testBigLastAddSize <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: For input string: "00000000000000000009911634398752770448"
[junit4] > at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:539249EBB446E3C8]:0)
[junit4] > at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
[junit4] > at java.base/java.lang.Long.parseLong(Long.java:692)
[junit4] > at java.base/java.lang.Long.parseLong(Long.java:817)
[junit4] > at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:153)
[junit4] > at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:141)
[junit4] > at org.apache.solr.update.TransactionLogTest.testBigLastAddSize(TransactionLogTest.java:34)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5ecc0d7b), locale=az-Latn, timezone=Etc/GMT-4
[junit4] 2> NOTE: Linux 4.13.0-41-generic amd64/Oracle Corporation 11-ea (64-bit)/cpus=8,threads=1,free=500915912,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TransactionLogTest, TransactionLogTest]
[junit4] Completed [2/10 (1!)] on J2 in 0.10s, 1 test, 1 error <<< FAILURES!
[...truncated 2 lines...]
[junit4] Suite: org.apache.solr.update.TransactionLogTest
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TransactionLogTest -Dtests.method=testBigLastAddSize -Dtests.seed=4B68E7405D081FC9 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.badapples=true -Dtests.locale=az-Latn -Dtests.timezone=Etc/GMT-4 -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.03s J2 | TransactionLogTest.testBigLastAddSize <<<
[junit4] > Throwable #1: java.lang.NumberFormatException: For input string: "000000000000000000015191380515270405197"
[junit4] > at __randomizedtesting.SeedInfo.seed([4B68E7405D081FC9:539249EBB446E3C8]:0)
[junit4] > at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
[junit4] > at java.base/java.lang.Long.parseLong(Long.java:692)
[junit4] > at java.base/java.lang.Long.parseLong(Long.java:817)
[junit4] > at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:153)
[junit4] > at org.apache.solr.update.TransactionLog.<init>(TransactionLog.java:141)
[junit4] > at org.apache.solr.update.TransactionLogTest.testBigLastAddSize(TransactionLogTest.java:34)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:832)
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4275eef6), locale=az-Latn, timezone=Etc/GMT-4
[junit4] 2> NOTE: Linux 4.13.0-41-generic amd64/Oracle Corporation 11-ea (64-bit)/cpus=8,threads=1,free=492000968,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TransactionLogTest, TransactionLogTest, TransactionLogTest]
[junit4] Completed [3/10 (2!)] on J2 in 0.05s, 1 test, 1 error <<< FAILURES!
[...truncated 1 lines...]
[junit4] Suite: org.apache.solr.handler.tagger.Tagger2Test
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.tagger.Tagger2Test_4B68E7405D081FC9-001/init-core-data-001
[junit4] 2> 2381 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2435 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.e.j.u.log Logging initialized @2473ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 2442 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 2455 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2471 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore
[junit4] 2> 2592 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.c.SolrResourceLoader Using system property solr.solr.home: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr
[junit4] 2> 2594 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2887 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 2977 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.s.IndexSchema [null] Schema name=minimal
[junit4] 2> 3001 WARN (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 3005 WARN (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 3098 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.h.t.WordLengthTaggingFilterFactory set minLength=4
[junit4] 2> 3114 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.6 with uniqueid field id
[junit4] 2> 3868 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13e9a45c
[junit4] 2> 3869 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13e9a45c
[junit4] 2> 3870 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13e9a45c
[junit4] 2> 3924 INFO (coreLoadExecutor-7-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3928 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.c.SolrResourceLoader [null] Added 2 libs to classloader, from paths: [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 3996 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
[junit4] 2> 4053 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.s.IndexSchema [collection1] Schema name=minimal
[junit4] 2> 4074 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.h.t.WordLengthTaggingFilterFactory set minLength=4
[junit4] 2> 4095 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.s.IndexSchema Loaded schema minimal/1.6 with uniqueid field id
[junit4] 2> 4096 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 4135 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@13e9a45c
[junit4] 2> 4142 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 4151 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J1/temp/solr.handler.tagger.Tagger2Test_4B68E7405D081FC9-001/init-core-data-001/]
[junit4] 2> 4708 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4709 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4721 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.s.SolrIndexSearcher Opening [Searcher@5c87036d[collection1] main]
[junit4] 2> 4736 WARN (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/core/src/test-files/solr/collection1/conf; switching to use InMemory storage instead.
[junit4] 2> 4754 INFO (coreLoadExecutor-7-thread-1) [ x:collection1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4755 INFO (searcherExecutor-8-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@5c87036d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4760 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 ####initCore end
[junit4] 2> 4767 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.h.t.TaggerTestCase testWDF(org.apache.solr.handler.tagger.Tagger2Test) being run...
[junit4] 2> 4768 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testWDF
[junit4] 2> 4782 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 4783 INFO (searcherExecutor-8-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_tag:[*+TO+*]&distrib=false&event=firstSearcher} hits=0 status=0 QTime=26
[junit4] 2> 4784 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 14
[junit4] 2> 4784 INFO (searcherExecutor-8-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 4805 INFO (searcherExecutor-8-thread-1-processing-x:collection1) [ x:collection1] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@5c87036d[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4885 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 98
[junit4] 2> 4893 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[1]} 0 1
[junit4] 2> 4895 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 4895 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@4438c218 commitCommandVersion:0
[junit4] 2> 4999 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@376dc2c[collection1] main]
[junit4] 2> 5001 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 5001 INFO (searcherExecutor-8-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@376dc2c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C2)))}
[junit4] 2> 5016 INFO (searcherExecutor-8-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_tag:[*+TO+*]&distrib=false&event=newSearcher} hits=2 status=0 QTime=15
[junit4] 2> 5019 INFO (searcherExecutor-8-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 5024 INFO (searcherExecutor-8-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@376dc2c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_0(8.0.0):C2)))}
[junit4] 2> 5028 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 132
[junit4] 2> 5044 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=15
[junit4] 2> 5047 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 5047 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 5047 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 5048 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 5049 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 5049 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status=0 QTime=0
[junit4] 2> 5050 INFO (TEST-Tagger2Test.testWDF-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT&field=name_tagWDF)}} status
[...truncated too long message...]
or-21-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 6162 INFO (searcherExecutor-21-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@34bfc1ee[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_2(8.0.0):C10)))}
[junit4] 2> 6163 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 18
[junit4] 2> 6164 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT)}} status=0 QTime=0
[junit4] 2> 6165 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT)}} status=0 QTime=0
[junit4] 2> 6166 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT)}} status=0 QTime=0
[junit4] 2> 6167 INFO (TEST-Tagger2Test.testLongestDominantRight-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLongestDominantRight
[junit4] OK 0.07s J0 | Tagger2Test.testLongestDominantRight
[junit4] 2> 6174 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.h.t.TaggerTestCase testMultibyteChar(org.apache.solr.handler.tagger.Tagger2Test) being run...
[junit4] 2> 6175 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMultibyteChar
[junit4] 2> 6175 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 [collection1] REMOVING ALL DOCUMENTS FROM INDEX
[junit4] 2> 6176 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={versions=true&wt=json&indent=true}{deleteByQuery=*:*} 0 0
[junit4] 2> 6178 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{add=[0]} 0 1
[junit4] 2> 6179 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 6179 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.SolrIndexWriter Calling setCommitData with IW:org.apache.solr.update.SolrIndexWriter@5f88f057 commitCommandVersion:0
[junit4] 2> 6183 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4119cfe3[collection1] main]
[junit4] 2> 6184 INFO (searcherExecutor-21-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener sending requests to Searcher@4119cfe3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.0.0):C1)))}
[junit4] 2> 6185 INFO (searcherExecutor-21-thread-1) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={q=name_tag:[*+TO+*]&distrib=false&event=newSearcher} hits=1 status=0 QTime=0
[junit4] 2> 6185 INFO (searcherExecutor-21-thread-1) [ ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 6185 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 6187 INFO (searcherExecutor-21-thread-1) [ ] o.a.s.c.SolrCore [collection1] Registered new searcher Searcher@4119cfe3[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader(Uninverting(_3(8.0.0):C1)))}
[junit4] 2> 6188 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=null path=null params={}{commit=} 0 9
[junit4] 2> 6190 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.c.S.Request [collection1] webapp=null path=null params={{params(),defaults(qt=/tag&wt=xml&overlaps=LONGEST_DOMINANT_RIGHT)}} status=0 QTime=0
[junit4] 2> 6190 INFO (TEST-Tagger2Test.testMultibyteChar-seed#[4B68E7405D081FC9]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMultibyteChar
[junit4] OK 0.02s J0 | Tagger2Test.testMultibyteChar
[junit4] 2> 6191 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.SolrTestCaseJ4 ###deleteCore
[junit4] 2> 6191 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2028370680
[junit4] 2> 6191 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 6192 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6ee109ec: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@62ac5eea
[junit4] 2> 6198 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 6198 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7220079: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@236ca8d5
[junit4] 2> 6198 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 6198 INFO (SUITE-Tagger2Test-seed#[4B68E7405D081FC9]-worker) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13b6687a: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@2e8365cb
[junit4] 2> 6211 INFO (coreCloseExecutor-25-thread-1) [ x:collection1] o.a.s.c.SolrCore [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@4b345d27
[junit4] 2> 6211 INFO (coreCloseExecutor-25-thread-1) [ x:collection1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1, tag=4b345d27
[junit4] 2> 6211 INFO (coreCloseExecutor-25-thread-1) [ x:collection1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1768e863: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1 / com.codahale.metrics.MetricRegistry@4882866
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/solr/build/solr-core/test/J0/temp/solr.handler.tagger.Tagger2Test_4B68E7405D081FC9-002
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {}, docValues:{}, maxPointsInLeafNode=730, maxMBSortInHeap=7.437464412778227, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4ceb61f6), locale=az-Latn, timezone=Etc/GMT-4
[junit4] 2> NOTE: Linux 4.13.0-41-generic amd64/Oracle Corporation 11-ea (64-bit)/cpus=8,threads=1,free=397872328,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [Tagger2Test, Tagger2Test]
[junit4] Completed [8/10 (7!)] on J0 in 0.84s, 5 tests, 1 failure, 1 skipped <<< FAILURES!
[...truncated 25 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1568: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-BadApples-master-Linux/lucene/common-build.xml:1092: There were test failures: 10 suites, 30 tests, 2 errors, 5 failures, 5 ignored [seed: 4B68E7405D081FC9]
Total time: 8 seconds
[repro] Setting last failure code to 256
[repro] Failures:
[repro] 2/5 failed: org.apache.solr.update.TransactionLogTest
[repro] 5/5 failed: org.apache.solr.handler.tagger.Tagger2Test
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Archiving artifacts
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Parsing warnings in console log with parser Java Compiler (javac)
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
<Git Blamer> Using GitBlamer to create author and commit information for all warnings.
<Git Blamer> GIT_COMMIT=124b5e047df697bfd61f1d14f87a0ee5d24f68d8, workspace=/var/lib/jenkins/workspace/Lucene-Solr-BadApples-master-Linux
[WARNINGS] Computing warning deltas based on reference build #45
Recording test results
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/var/lib/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2