You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2017/12/07 23:14:45 UTC
[JENKINS] Lucene-Solr-Tests-7.2 - Build # 3 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-7.2/3/
6 tests failed.
FAILED: org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForImplicitRouter
Error Message:
Could not load collection from ZK: withShardField
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK: withShardField
at __randomizedtesting.SeedInfo.seed([91C5CAEB023EC8A7:C4952279AEC70757]:0)
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1123)
at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:648)
at org.apache.solr.client.solrj.impl.CloudSolrClient.getDocCollection(CloudSolrClient.java:1205)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:848)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.cloud.CustomCollectionTest.testRouteFieldForImplicitRouter(CustomCollectionTest.java:141)
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)
Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/withShardField/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1135)
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1121)
... 46 more
FAILED: org.apache.solr.cloud.RecoveryZkTest.test
Error Message:
Stack Trace:
java.util.concurrent.TimeoutException
at __randomizedtesting.SeedInfo.seed([91C5CAEB023EC8A7:1991F531ACC2A55F]:0)
at org.apache.solr.common.cloud.ZkStateReader.waitForState(ZkStateReader.java:1276)
at org.apache.solr.client.solrj.impl.CloudSolrClient.waitForState(CloudSolrClient.java:448)
at org.apache.solr.cloud.RecoveryZkTest.test(RecoveryZkTest.java:122)
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.TestLocalFSCloudBackupRestore.test
Error Message:
expected:<COMPLETED> but was:<RUNNING>
Stack Trace:
java.lang.AssertionError: expected:<COMPLETED> but was:<RUNNING>
at __randomizedtesting.SeedInfo.seed([91C5CAEB023EC8A7:1991F531ACC2A55F]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.AbstractCloudBackupRestoreTestCase.testBackupAndRestore(AbstractCloudBackupRestoreTestCase.java:287)
at org.apache.solr.cloud.AbstractCloudBackupRestoreTestCase.test(AbstractCloudBackupRestoreTestCase.java:140)
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.TestSegmentSorting.testSegmentTerminateEarly
Error Message:
Could not load collection from ZK: testSegmentTerminateEarly
Stack Trace:
org.apache.solr.common.SolrException: Could not load collection from ZK: testSegmentTerminateEarly
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1123)
at org.apache.solr.common.cloud.ZkStateReader$LazyCollectionRef.get(ZkStateReader.java:648)
at org.apache.solr.client.solrj.impl.CloudSolrClient.getDocCollection(CloudSolrClient.java:1205)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:848)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:945)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:173)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:138)
at org.apache.solr.client.solrj.SolrClient.add(SolrClient.java:152)
at org.apache.solr.cloud.SegmentTerminateEarlyTestState.addDocuments(SegmentTerminateEarlyTestState.java:85)
at org.apache.solr.cloud.TestSegmentSorting.testSegmentTerminateEarly(TestSegmentSorting.java:100)
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 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 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: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/testSegmentTerminateEarly/state.json
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
at org.apache.solr.common.cloud.ZkStateReader.fetchCollectionState(ZkStateReader.java:1135)
at org.apache.solr.common.cloud.ZkStateReader.getCollectionLive(ZkStateReader.java:1121)
... 51 more
FAILED: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeMarkersRegistration
Error Message:
Path /autoscaling/nodeAdded/127.0.0.1:35516_solr wasn't created
Stack Trace:
java.lang.AssertionError: Path /autoscaling/nodeAdded/127.0.0.1:35516_solr wasn't created
at __randomizedtesting.SeedInfo.seed([91C5CAEB023EC8A7:897F42E70C0B0548]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest.testNodeMarkersRegistration(TriggerIntegrationTest.java:931)
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.core.TestCustomStream.testDynamicLoadingCustomStream
Error Message:
KeeperErrorCode = Session expired for /clusterstate.json
Stack Trace:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /clusterstate.json
at __randomizedtesting.SeedInfo.seed([91C5CAEB023EC8A7:E170C3C9F8C48310]:0)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
at org.apache.solr.common.cloud.ZkStateReader.refreshLegacyClusterState(ZkStateReader.java:542)
at org.apache.solr.common.cloud.ZkStateReader.forceUpdateCollection(ZkStateReader.java:352)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.updateMappingsFromZk(AbstractFullDistribZkTestBase.java:673)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.updateMappingsFromZk(AbstractFullDistribZkTestBase.java:668)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createJettys(AbstractFullDistribZkTestBase.java:463)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:333)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:991)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
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)
Build Log:
[...truncated 11823 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.TriggerIntegrationTest
[junit4] 2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.2/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_91C5CAEB023EC8A7-001/init-core-data-001
[junit4] 2> 518159 INFO (SUITE-TriggerIntegrationTest-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 518178 INFO (SUITE-TriggerIntegrationTest-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 518178 INFO (SUITE-TriggerIntegrationTest-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 518223 INFO (SUITE-TriggerIntegrationTest-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.2/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_91C5CAEB023EC8A7-001/tempDir-001
[junit4] 2> 518223 INFO (SUITE-TriggerIntegrationTest-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 518237 INFO (Thread-151) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 518237 INFO (Thread-151) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 518440 ERROR (Thread-151) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 518440 INFO (SUITE-TriggerIntegrationTest-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:34029
[junit4] 2> 518501 INFO (zkConnectionManagerCallback-458-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 518558 INFO (jetty-launcher-455-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 518570 INFO (jetty-launcher-455-thread-2) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 518602 INFO (jetty-launcher-455-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@490d9bda{/solr,null,AVAILABLE}
[junit4] 2> 518604 INFO (jetty-launcher-455-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@64ca276a{HTTP/1.1,[http/1.1]}{127.0.0.1:44043}
[junit4] 2> 518604 INFO (jetty-launcher-455-thread-1) [ ] o.e.j.s.Server Started @546838ms
[junit4] 2> 518604 INFO (jetty-launcher-455-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44043}
[junit4] 2> 518605 ERROR (jetty-launcher-455-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 518605 INFO (jetty-launcher-455-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0
[junit4] 2> 518605 INFO (jetty-launcher-455-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 518605 INFO (jetty-launcher-455-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 518605 INFO (jetty-launcher-455-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-07T19:50:00.672Z
[junit4] 2> 518697 INFO (jetty-launcher-455-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6db4a67f{/solr,null,AVAILABLE}
[junit4] 2> 518697 INFO (jetty-launcher-455-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2fe1c47e{HTTP/1.1,[http/1.1]}{127.0.0.1:37172}
[junit4] 2> 518697 INFO (jetty-launcher-455-thread-2) [ ] o.e.j.s.Server Started @546931ms
[junit4] 2> 518697 INFO (jetty-launcher-455-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37172}
[junit4] 2> 518698 ERROR (jetty-launcher-455-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 518698 INFO (jetty-launcher-455-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0
[junit4] 2> 518698 INFO (jetty-launcher-455-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 518698 INFO (jetty-launcher-455-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 518698 INFO (jetty-launcher-455-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-07T19:50:00.765Z
[junit4] 2> 518789 INFO (zkConnectionManagerCallback-462-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 518789 INFO (jetty-launcher-455-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 518882 INFO (zkConnectionManagerCallback-460-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 518935 INFO (jetty-launcher-455-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 518956 INFO (jetty-launcher-455-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34029/solr
[junit4] 2> 518993 INFO (zkConnectionManagerCallback-466-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 519236 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x16032870ff00003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 519301 INFO (zkConnectionManagerCallback-468-thread-1-processing-n:127.0.0.1:37172_solr) [n:127.0.0.1:37172_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 519364 INFO (jetty-launcher-455-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34029/solr
[junit4] 2> 519436 INFO (zkConnectionManagerCallback-472-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 519556 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x16032870ff00005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 519613 INFO (zkConnectionManagerCallback-475-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 520184 INFO (jetty-launcher-455-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 520238 INFO (jetty-launcher-455-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44043_solr
[junit4] 2> 520238 INFO (jetty-launcher-455-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.c.Overseer Overseer (id=99134747471511558-127.0.0.1:44043_solr-n_0000000000) starting
[junit4] 2> 520436 INFO (jetty-launcher-455-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44043_solr
[junit4] 2> 520437 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Adding .autoAddReplicas trigger
[junit4] 2> 520490 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 520509 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeLostTrigger Initial livenodes: []
[junit4] 2> 520708 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion -1
[junit4] 2> 520708 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 520703 INFO (jetty-launcher-455-thread-2) [n:127.0.0.1:37172_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 520907 INFO (jetty-launcher-455-thread-2) [n:127.0.0.1:37172_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 521104 INFO (jetty-launcher-455-thread-2) [n:127.0.0.1:37172_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37172_solr
[junit4] 2> 521118 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 521118 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 521126 INFO (OverseerStateUpdate-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 521119 DEBUG (ScheduledTrigger-450-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: []
[junit4] 2> 521229 INFO (zkCallback-467-thread-1-processing-n:127.0.0.1:37172_solr) [n:127.0.0.1:37172_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 522047 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1527151762
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1829729401
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1539829076
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1567133527
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=895650436
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=593005805
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1192323231
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1578784029
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=2140001008
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=408225288
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1414320141
[junit4] 2> 522122 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1492305188
[junit4] 2> 522123 ERROR (Finalizer) [ ] o.a.s.c.CoreContainer CoreContainer was not close prior to finalize(), indicates a bug -- POSSIBLE RESOURCE LEAK!!! instance=1241093784
[junit4] 2> 522135 INFO (jetty-launcher-455-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44043.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 522193 DEBUG (ScheduledTrigger-450-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:44043_solr, 127.0.0.1:37172_solr]
[junit4] 2> 522288 INFO (jetty-launcher-455-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44043.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 522288 INFO (jetty-launcher-455-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44043.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 522290 INFO (jetty-launcher-455-thread-1) [n:127.0.0.1:44043_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.2/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_91C5CAEB023EC8A7-001/tempDir-001/node1/.
[junit4] 2> 522761 INFO (jetty-launcher-455-thread-2) [n:127.0.0.1:37172_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37172.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 522922 INFO (jetty-launcher-455-thread-2) [n:127.0.0.1:37172_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37172.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 522922 INFO (jetty-launcher-455-thread-2) [n:127.0.0.1:37172_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37172.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 522923 INFO (jetty-launcher-455-thread-2) [n:127.0.0.1:37172_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.2/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_91C5CAEB023EC8A7-001/tempDir-001/node2/.
[junit4] 2> 523236 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: [127.0.0.1:44043_solr, 127.0.0.1:37172_solr]
[junit4] 2> 523249 INFO (zkConnectionManagerCallback-479-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 523342 INFO (zkConnectionManagerCallback-483-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 523343 INFO (SUITE-TriggerIntegrationTest-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 523344 INFO (SUITE-TriggerIntegrationTest-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34029/solr ready
[junit4] 2> 523457 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testListeners
[junit4] 2> 523458 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 523458 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 523460 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 2
[junit4] 2> 523460 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 523461 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 523461 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 523594 INFO (zkConnectionManagerCallback-487-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 523596 INFO (qtp1658274908-1466) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 523596 INFO (qtp1658274908-1466) [n:127.0.0.1:44043_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34029/solr ready
[junit4] 2> 523616 INFO (qtp1362735516-1477) [n:127.0.0.1:37172_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 523621 INFO (qtp1658274908-1464) [n:127.0.0.1:44043_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 523623 DEBUG (qtp1658274908-1466) [n:127.0.0.1:44043_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 523624 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 523630 INFO (qtp1658274908-1466) [n:127.0.0.1:44043_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=130
[junit4] 2> 523631 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:44043_solr, 127.0.0.1:37172_solr]
[junit4] 2> 523631 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=3, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}, {name=test1, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}], enabled=true}
[junit4] 2> 523631 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 523633 INFO (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 523638 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 523639 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 523639 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 523679 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:37172_solr]
[junit4] 2> 523701 INFO (zkConnectionManagerCallback-491-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 523703 INFO (qtp1658274908-1467) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 523703 INFO (qtp1658274908-1467) [n:127.0.0.1:44043_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34029/solr ready
[junit4] 2> 523705 INFO (qtp1362735516-1478) [n:127.0.0.1:37172_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 523706 INFO (qtp1658274908-1464) [n:127.0.0.1:44043_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 523710 DEBUG (qtp1658274908-1467) [n:127.0.0.1:44043_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 523713 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 523713 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:44043_solr, 127.0.0.1:37172_solr]
[junit4] 2> 523713 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=3, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}, {name=test1, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}], enabled=true}
[junit4] 2> 523713 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 523714 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 523714 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 523714 INFO (qtp1658274908-1467) [n:127.0.0.1:44043_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=77
[junit4] 2> 523753 INFO (zkConnectionManagerCallback-495-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 523802 INFO (qtp1658274908-1469) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 523803 INFO (qtp1658274908-1469) [n:127.0.0.1:44043_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34029/solr ready
[junit4] 2> 523804 INFO (qtp1362735516-1478) [n:127.0.0.1:37172_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 523805 INFO (qtp1658274908-1470) [n:127.0.0.1:44043_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 523805 DEBUG (qtp1658274908-1469) [n:127.0.0.1:44043_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 523806 INFO (qtp1658274908-1469) [n:127.0.0.1:44043_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=91
[junit4] 2> 523808 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 523808 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 523810 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:44043_solr, 127.0.0.1:37172_solr]
[junit4] 2> 523810 DEBUG (zkCallback-474-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=3, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}, {name=test1, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction}], enabled=true}
[junit4] 2> 523812 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 523812 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 523813 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 523814 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@193b7f63{/solr,null,AVAILABLE}
[junit4] 2> 523814 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2603daf1{HTTP/1.1,[http/1.1]}{127.0.0.1:45240}
[junit4] 2> 523814 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.Server Started @552047ms
[junit4] 2> 523814 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45240}
[junit4] 2> 523814 ERROR (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 523814 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0
[junit4] 2> 523814 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 523815 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 523815 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-07T19:50:05.882Z
[junit4] 2> 523826 INFO (zkConnectionManagerCallback-497-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 523832 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 523868 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34029/solr
[junit4] 2> 523886 INFO (zkConnectionManagerCallback-501-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 523909 INFO (zkConnectionManagerCallback-503-thread-1-processing-n:127.0.0.1:45240_solr) [n:127.0.0.1:45240_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 523916 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:45240_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 523917 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:45240_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 523918 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:45240_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45240_solr
[junit4] 2> 523920 INFO (zkCallback-467-thread-1-processing-n:127.0.0.1:37172_solr) [n:127.0.0.1:37172_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 523926 INFO (zkCallback-482-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 523931 INFO (zkCallback-474-thread-2-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 523933 INFO (zkCallback-502-thread-1-processing-n:127.0.0.1:45240_solr) [n:127.0.0.1:45240_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 524124 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:45240_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45240.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 524133 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:45240_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45240.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 524133 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:45240_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45240.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 524135 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:45240_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.2/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_91C5CAEB023EC8A7-001/tempDir-001/node3/.
[junit4] 2> 524681 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 524681 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr]
[junit4] 2> 524681 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:45240_solr at time 1512676206748000000
[junit4] 2> 525682 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 525682 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr]
[junit4] 2> 526683 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 526683 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr]
[junit4] 2> 527683 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 527683 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr]
[junit4] 2> 527683 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:45240_solr] added at times [1512676206748000000], now=1512676209750000000
[junit4] 2> 527684 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"14fe1afedb665f00Tde8odefd8i74kal8dbci967p5",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1512676206748000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1512676206748000000],
[junit4] 2> "nodeNames":["127.0.0.1:45240_solr"]}}
[junit4] 2> 527685 INFO (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging.
[junit4] 2> 527700 DEBUG (AutoscalingActionExecutor-451-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"14fe1afedb665f00Tde8odefd8i74kal8dbci967p5",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1512676206748000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1512676206748000000],
[junit4] 2> "_enqueue_time_":1512676209751000000,
[junit4] 2> "nodeNames":["127.0.0.1:45240_solr"]}}
[junit4] 2> 528700 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 528700 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr]
[junit4] 2> 529700 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 529700 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr]
[junit4] 2> 529717 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 529756 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7ee7bae6{/solr,null,AVAILABLE}
[junit4] 2> 529756 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@7e77eb2{HTTP/1.1,[http/1.1]}{127.0.0.1:46190}
[junit4] 2> 529757 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.Server Started @557990ms
[junit4] 2> 529757 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46190}
[junit4] 2> 529757 ERROR (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 529757 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0
[junit4] 2> 529757 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 529757 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 529757 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-07T19:50:11.824Z
[junit4] 2> 529790 INFO (zkConnectionManagerCallback-506-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 529790 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 529814 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34029/solr
[junit4] 2> 529846 INFO (zkConnectionManagerCallback-510-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 529870 INFO (zkConnectionManagerCallback-512-thread-1-processing-n:127.0.0.1:46190_solr) [n:127.0.0.1:46190_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 529878 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:46190_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 529879 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:46190_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 529881 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:46190_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46190_solr
[junit4] 2> 529883 INFO (zkCallback-502-thread-1-processing-n:127.0.0.1:45240_solr) [n:127.0.0.1:45240_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 529883 INFO (zkCallback-467-thread-1-processing-n:127.0.0.1:37172_solr) [n:127.0.0.1:37172_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 529884 INFO (zkCallback-482-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 529884 INFO (zkCallback-474-thread-2-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 529902 INFO (zkCallback-511-thread-1-processing-n:127.0.0.1:46190_solr) [n:127.0.0.1:46190_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 530235 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:46190_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46190.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 530268 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:46190_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46190.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 530268 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:46190_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46190.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 530269 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:46190_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.2/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_91C5CAEB023EC8A7-001/tempDir-001/node4/.
[junit4] 2> 530701 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 530701 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:46190_solr]
[junit4] 2> 530701 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:46190_solr at time 1512676212768000000
[junit4] 2> 531702 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 531702 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:46190_solr]
[junit4] 2> 532702 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 532702 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:46190_solr]
[junit4] 2> 533712 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 533713 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:46190_solr]
[junit4] 2> 533713 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:46190_solr] added at times [1512676212768000000], now=1512676215780000000
[junit4] 2> 533713 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"14fe1b0042384800Tde8odefd8i74kal8dbci967p7",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1512676212768000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1512676212768000000],
[junit4] 2> "nodeNames":["127.0.0.1:46190_solr"]}}
[junit4] 2> 533713 DEBUG (AutoscalingActionExecutor-451-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"14fe1b0042384800Tde8odefd8i74kal8dbci967p7",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1512676212768000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1512676212768000000],
[junit4] 2> "_enqueue_time_":1512676215780000000,
[junit4] 2> "nodeNames":["127.0.0.1:46190_solr"]}}
[junit4] 2> 533714 INFO (AutoscalingActionExecutor-451-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.ActionThrottle The last action attempt started 5998ms ago.
[junit4] 2> 533714 WARN (AutoscalingActionExecutor-451-thread-1-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.ScheduledTriggers Exception executing actions
[junit4] 2> java.lang.Exception: Error executing action: test1 for trigger event: {
[junit4] 2> "id":"14fe1b0042384800Tde8odefd8i74kal8dbci967p7",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1512676212768000000,
[junit4] 2> "eventType":"NODEADDED",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1512676212768000000],
[junit4] 2> "_enqueue_time_":1512676215780000000,
[junit4] 2> "nodeNames":["127.0.0.1:46190_solr"]}}
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:313)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.lang.RuntimeException: failure
[junit4] 2> at org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestDummyAction.process(TriggerIntegrationTest.java:1012)
[junit4] 2> at org.apache.solr.cloud.autoscaling.ScheduledTriggers.lambda$null$3(ScheduledTriggers.java:310)
[junit4] 2> ... 6 more
[junit4] 2> 534719 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 534720 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:46190_solr]
[junit4] 2> 535722 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 535722 DEBUG (ScheduledTrigger-450-thread-3) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:46190_solr]
[junit4] 2> 535730 INFO (TEST-TriggerIntegrationTest.testListeners-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testListeners
[junit4] 2> 535850 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testNodeAddedTrigger
[junit4] 2> 535851 DEBUG (zkCallback-474-thread-2-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 535851 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 535852 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.c.a.TriggerIntegrationTest /autoscaling.json reset, new znode version 6
[junit4] 2> 535854 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 535855 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeAdded markers
[junit4] 2> 535855 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 535925 INFO (zkConnectionManagerCallback-517-thread-1-processing-n:127.0.0.1:37172_solr) [n:127.0.0.1:37172_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 535926 INFO (qtp1362735516-1481) [n:127.0.0.1:37172_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 535927 INFO (qtp1362735516-1481) [n:127.0.0.1:37172_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34029/solr ready
[junit4] 2> 535928 INFO (qtp1658274908-1470) [n:127.0.0.1:44043_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 535960 INFO (qtp2083213651-1539) [n:127.0.0.1:45240_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 535975 INFO (qtp1362735516-1476) [n:127.0.0.1:37172_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 535995 INFO (qtp659351658-1564) [n:127.0.0.1:46190_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CORE.coreName&wt=javabin&version=2&group=solr.core} status=0 QTime=0
[junit4] 2> 535996 DEBUG (qtp1362735516-1481) [n:127.0.0.1:37172_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 535997 DEBUG (zkCallback-474-thread-2-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7
[junit4] 2> 536010 INFO (qtp1362735516-1481) [n:127.0.0.1:37172_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/autoscaling params={wt=javabin&version=2} status=0 QTime=115
[junit4] 2> 536026 DEBUG (zkCallback-474-thread-2-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Initial livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:46190_solr]
[junit4] 2> 536026 DEBUG (zkCallback-474-thread-2-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger instantiated with properties: {event=nodeAdded, waitFor=3, actions=[{name=test, class=org.apache.solr.cloud.autoscaling.TriggerIntegrationTest$TestTriggerAction}], enabled=true}
[junit4] 2> 536027 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 536028 INFO (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.TriggerIntegrationTest TestTriggerAction init
[junit4] 2> 536029 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 536032 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread -- clean old nodeLost markers
[junit4] 2> 536032 DEBUG (OverseerAutoScalingTriggerThread-99134747471511558-127.0.0.1:44043_solr-n_0000000000) [n:127.0.0.1:44043_solr ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 536033 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 536033 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:46190_solr]
[junit4] 2> 536102 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@8b66010{/solr,null,AVAILABLE}
[junit4] 2> 536103 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3cd03de0{HTTP/1.1,[http/1.1]}{127.0.0.1:42011}
[junit4] 2> 536103 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.e.j.s.Server Started @564336ms
[junit4] 2> 536103 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42011}
[junit4] 2> 536103 ERROR (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 536116 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0
[junit4] 2> 536116 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 536116 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 536116 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-07T19:50:18.183Z
[junit4] 2> 536156 INFO (zkConnectionManagerCallback-519-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 536165 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 536209 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34029/solr
[junit4] 2> 536254 INFO (zkConnectionManagerCallback-523-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 536278 INFO (zkConnectionManagerCallback-525-thread-1-processing-n:127.0.0.1:42011_solr) [n:127.0.0.1:42011_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 536291 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:42011_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 536292 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:42011_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 536294 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:42011_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42011_solr
[junit4] 2> 536327 INFO (zkCallback-502-thread-1-processing-n:127.0.0.1:45240_solr) [n:127.0.0.1:45240_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 536327 INFO (zkCallback-467-thread-1-processing-n:127.0.0.1:37172_solr) [n:127.0.0.1:37172_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 536327 INFO (zkCallback-474-thread-2-processing-n:127.0.0.1:44043_solr) [n:127.0.0.1:44043_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 536328 INFO (zkCallback-511-thread-1-processing-n:127.0.0.1:46190_solr) [n:127.0.0.1:46190_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 536328 INFO (zkCallback-524-thread-1-processing-n:127.0.0.1:42011_solr) [n:127.0.0.1:42011_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 536335 INFO (zkCallback-482-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 536473 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:42011_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42011.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 536509 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:42011_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42011.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 536509 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:42011_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42011.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@63cbd4c1
[junit4] 2> 536511 INFO (TEST-TriggerIntegrationTest.testNodeAddedTrigger-seed#[91C5CAEB023EC8A7]) [n:127.0.0.1:42011_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.2/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.TriggerIntegrationTest_91C5CAEB023EC8A7-001/tempDir-001/node5/.
[junit4] 2> 537033 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 537034 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:42011_solr, 127.0.0.1:46190_solr]
[junit4] 2> 537034 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Tracking new node: 127.0.0.1:42011_solr at time 1512676219101000000
[junit4] 2> 538048 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 538048 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:42011_solr, 127.0.0.1:46190_solr]
[junit4] 2> 539060 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 539060 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:42011_solr, 127.0.0.1:46190_solr]
[junit4] 2> 540061 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Running NodeAddedTrigger node_added_trigger
[junit4] 2> 540061 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger Found livenodes: [127.0.0.1:44043_solr, 127.0.0.1:45240_solr, 127.0.0.1:37172_solr, 127.0.0.1:42011_solr, 127.0.0.1:46190_solr]
[junit4] 2> 540061 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.NodeAddedTrigger NodeAddedTrigger node_added_trigger firing registered processor for nodes: [127.0.0.1:42011_solr] added at times [1512676219101000000], now=1512676222128000000
[junit4] 2> 540061 DEBUG (ScheduledTrigger-450-thread-2) [n:127.0.0.1:44043_solr ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"14fe1b01bbb23140Tde8odefd8i74kal8dbci967p8",
[junit4] 2> "source":"node_added_trigger",
[junit4] 2> "eventTime":1512676219101000000,
[junit4] 2> "eventType":"NODEADDED
[...truncated too long message...]
[junit4] 2> "type":"NRT"}}},
[junit4] 2> "shard2":{
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node7":{
[junit4] 2> "core":"testSegmentTerminateEarly_shard2_replica_n4",
[junit4] 2> "base_url":"http://127.0.0.1:44693/solr",
[junit4] 2> "node_name":"127.0.0.1:44693_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"},
[junit4] 2> "core_node8":{
[junit4] 2> "core":"testSegmentTerminateEarly_shard2_replica_n5",
[junit4] 2> "base_url":"http://127.0.0.1:37055/solr",
[junit4] 2> "node_name":"127.0.0.1:37055_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true"}}}},
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0"} with live_nodes=[]
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:772)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderUrl(ZkStateReader.java:737)
[junit4] 2> at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:1178)
[junit4] 2> at org.apache.solr.cloud.ZkController.register(ZkController.java:1079)
[junit4] 2> at org.apache.solr.cloud.ZkController$RegisterCoreAsync.call(ZkController.java:256)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 7020511 INFO (jetty-closer-3821-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5531210d{/solr,null,UNAVAILABLE}
[junit4] 2> 7020798 INFO (jetty-closer-3821-thread-5) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5c460636{/solr,null,UNAVAILABLE}
[junit4] 2> 7022006 INFO (jetty-closer-3821-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@57d16604{/solr,null,UNAVAILABLE}
[junit4] 2> 7022050 ERROR (SUITE-TestSegmentSorting-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 7022067 INFO (SUITE-TestSegmentSorting-seed#[91C5CAEB023EC8A7]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36186 36186
[junit4] 2> 7022154 INFO (Thread-4802) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:36186 36186
[junit4] 2> 7022155 WARN (Thread-4802) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 12 /solr/configs/class org.apache.solr.cloud.TestSegmentSorting_configSet
[junit4] 2> 11 /solr/aliases.json
[junit4] 2> 11 /solr/clusterprops.json
[junit4] 2> 9 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 36 /solr/collections/testAtomicUpdateOfSegmentSortField/state.json
[junit4] 2> 32 /solr/collections/testSegmentTerminateEarly/state.json
[junit4] 2> 11 /solr/clusterstate.json
[junit4] 2> 2 /solr/overseer_elect/election/99135163736981514-127.0.0.1:45629_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 13 /solr/collections
[junit4] 2> 11 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-7.2/solr/build/solr-core/test/J1/temp/solr.cloud.TestSegmentSorting_91C5CAEB023EC8A7-001
[junit4] 2> Dec 07, 2017 9:38:19 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70): {multiDefault=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=Memory)}, docValues:{quad_l1=DocValuesFormat(name=Lucene70), range_facet_l_dv=DocValuesFormat(name=Lucene70), _version_=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Memory), range_facet_i_dv=DocValuesFormat(name=Direct), intDvoDefault=DocValuesFormat(name=Lucene70), timestamp_i_dvo=DocValuesFormat(name=Direct), range_facet_l=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Direct), odd_l1=DocValuesFormat(name=Lucene70)}, maxPointsInLeafNode=1351, maxMBSortInHeap=5.389746636021955, sim=RandomSimilarity(queryNorm=true): {}, locale=es-AR, timezone=Europe/Minsk
[junit4] 2> NOTE: Linux 4.4.0-83-generic amd64/Oracle Corporation 1.8.0_152 (64-bit)/cpus=4,threads=1,free=215383576,total=522190848
[junit4] 2> NOTE: All tests run in this JVM: [ChaosMonkeySafeLeaderWithPullReplicasTest, TestRemoteStreaming, BlobRepositoryCloudTest, BJQParserTest, BitVectorTest, SolrCLIZkUtilsTest, TestWriterPerf, DistribDocExpirationUpdateProcessorTest, TestPrepRecovery, ShardSplitTest, SolrIndexSplitterTest, CollectionsAPIDistributedZkTest, TestBackupRepositoryFactory, AtomicUpdateProcessorFactoryTest, TermVectorComponentDistributedTest, CollectionTooManyReplicasTest, TestImpersonationWithHadoopAuth, TestEmbeddedSolrServerAdminHandler, HdfsAutoAddReplicasIntegrationTest, RollingRestartTest, LukeRequestHandlerTest, CdcrUpdateLogTest, TestJmxIntegration, NodeLostTriggerTest, RegexBoostProcessorTest, ExitableDirectoryReaderTest, TestRangeQuery, CdcrReplicationHandlerTest, TestSubQueryTransformerCrossCore, TestInfoStreamLogging, TestFastWriter, SubstringBytesRefFilterTest, TestFastLRUCache, TestOmitPositions, TestNumericRangeQuery32, SampleTest, TestSSLRandomization, TestLegacyFieldCache, SolrJmxReporterCloudTest, OpenExchangeRatesOrgProviderTest, SuggesterTSTTest, CdcrBidirectionalTest, TestFaceting, BinaryUpdateRequestHandlerTest, TestSizeLimitedDistributedMap, NotRequiredUniqueKeyTest, TestJsonRequest, PathHierarchyTokenizerFactoryTest, RankQueryTest, TestRandomCollapseQParserPlugin, TestMultiWordSynonyms, TestSolr4Spatial, TestDistribIDF, TestDocSet, TestXmlQParserPlugin, TestCustomStream, TestSimpleQParserPlugin, TestSolrCloudWithKerberosAlt, TestHdfsBackupRestoreCore, ZkCLITest, SpellingQueryConverterTest, TestPayloadCheckQParserPlugin, ClassificationUpdateProcessorIntegrationTest, SchemaVersionSpecificBehaviorTest, TestGraphMLResponseWriter, OutputWriterTest, BadIndexSchemaTest, TestAnalyzedSuggestions, SolrCoreMetricManagerTest, TestCursorMarkWithoutUniqueKey, TestMinMaxOnMultiValuedField, TestUseDocValuesAsStored2, HighlighterConfigTest, JSONWriterTest, TestPolicyCloud, TestCollapseQParserPlugin, QueryEqualityTest, TestConfigSetsAPIExclusivity, TestSolrCoreParser, SolrMetricsIntegrationTest, TestPseudoReturnFields, TestBlobHandler, TestGraphTermsQParserPlugin, ShowFileRequestHandlerTest, CreateCollectionCleanupTest, TestConfigSetProperties, HdfsUnloadDistributedZkTest, SolrIndexMetricsTest, MultiThreadedOCPTest, DistributedFacetPivotSmallAdvancedTest, TestFileDictionaryLookup, HdfsChaosMonkeySafeLeaderTest, LegacyCloudClusterPropTest, TestStressLucene, TestXIncludeConfig, DirectUpdateHandlerTest, DistributedFacetExistsSmallTest, PolyFieldTest, SuggestComponentTest, TestFieldTypeResource, SolrCloudReportersTest, AssignTest, TestLazyCores, TestCollationFieldDocValues, TestUniqueKeyFieldResource, TestAtomicUpdateErrorCases, DeleteLastCustomShardedReplicaTest, HttpSolrCallGetCoreTest, TestRTGBase, SynonymTokenizerTest, TestFieldCacheSortRandom, HdfsDirectoryTest, DocValuesMultiTest, HdfsRecoverLeaseTest, SpellCheckCollatorWithCollapseTest, SuggesterFSTTest, TestPerFieldSimilarity, RequestHandlersTest, TestApiFramework, TestTolerantUpdateProcessorCloud, TestSlowCompositeReaderWrapper, TestSchemaManager, TestDeleteCollectionOnDownNodes, LeaderElectionTest, ZkSolrClientTest, ShardRoutingCustomTest, TestDistributedSearch, TestFiltering, TestCSVLoader, DefaultValueUpdateProcessorTest, TestSolrDeletionPolicy1, XsltUpdateRequestHandlerTest, DebugComponentTest, IndexBasedSpellCheckerTest, DisMaxRequestHandlerTest, TestReversedWildcardFilterFactory, TestQueryTypes, TermsComponentTest, TestPHPSerializedResponseWriter, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, TestMergePolicyConfig, TestConfig, DirectSolrConnectionTest, SpellPossibilityIteratorTest, TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory, PluginInfoTest, PreAnalyzedFieldTest, FileUtilsTest, DistributedMLTComponentTest, TestCrossCoreJoin, TestSimpleTrackingShardHandler, TestTolerantSearch, TestLuceneIndexBackCompat, AssignBackwardCompatibilityTest, CloudExitableDirectoryReaderTest, CollectionReloadTest, CollectionsAPISolrJTest, DeleteReplicaTest, DistribJoinFromCollectionTest, DistributedVersionInfoTest, DocValuesNotIndexedTest, HttpPartitionTest, MissingSegmentRecoveryTest, MoveReplicaHDFSFailoverTest, ReplaceNodeTest, SimpleCollectionCreateDeleteTest, TestCloudJSONFacetJoinDomain, TestClusterProperties, TestCollectionsAPIViaSolrCloudCluster, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestHdfsCloudBackupRestore, TestRandomFlRTGCloud, TestReplicaProperties, TestSegmentSorting]
[junit4] Completed [561/751 (6!)] on J1 in 147.99s, 2 tests, 1 error <<< FAILURES!
[...truncated 48010 lines...]