You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/05/18 22:15:34 UTC
[JENKINS] Lucene-Solr-Tests-8.x - Build # 1561 - Unstable
Build: https://builds.apache.org/job/Lucene-Solr-Tests-8.x/1561/
2 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMaxOps
Error Message:
expected:<1> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<1> but was:<2>
at __randomizedtesting.SeedInfo.seed([580FCD4CD2BEA015:B9F080299C1B9DE0]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.junit.Assert.assertEquals(Assert.java:631)
at org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest.testMaxOps(IndexSizeTriggerTest.java:579)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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.client.solrj.io.stream.MathExpressionTest.testGeometricDistribution
Error Message:
expected:<0.4797> but was:<0.5>
Stack Trace:
java.lang.AssertionError: expected:<0.4797> but was:<0.5>
at __randomizedtesting.SeedInfo.seed([B326D5B797172764:787D050FCB9FE90]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:553)
at org.junit.Assert.assertEquals(Assert.java:683)
at org.apache.solr.client.solrj.io.stream.MathExpressionTest.testGeometricDistribution(MathExpressionTest.java:3692)
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:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
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:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
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 15043 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest
[junit4] 2> 3287294 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 3287294 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 3287295 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_580FCD4CD2BEA015-001/data-dir-154-001
[junit4] 2> 3287295 WARN (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=28 numCloses=28
[junit4] 2> 3287295 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 3287297 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 3287297 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_580FCD4CD2BEA015-001/tempDir-001
[junit4] 2> 3287298 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3287298 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 3287298 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3287398 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 41443
[junit4] 2> 3287398 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:41443
[junit4] 2> 3287398 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41443
[junit4] 2> 3287398 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41443
[junit4] 2> 3287400 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3287402 INFO (zkConnectionManagerCallback-20336-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3287402 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3287405 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3287406 INFO (zkConnectionManagerCallback-20338-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3287406 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3287406 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3287407 INFO (zkConnectionManagerCallback-20340-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3287407 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3287512 WARN (jetty-launcher-20341-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3287512 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3287512 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 3287512 INFO (jetty-launcher-20341-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_191-b12
[junit4] 2> 3287514 WARN (jetty-launcher-20341-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 3287514 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 3287514 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 3287514 INFO (jetty-launcher-20341-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_191-b12
[junit4] 2> 3287523 INFO (jetty-launcher-20341-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3287523 INFO (jetty-launcher-20341-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3287523 INFO (jetty-launcher-20341-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3287523 INFO (jetty-launcher-20341-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 3287523 INFO (jetty-launcher-20341-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 3287523 INFO (jetty-launcher-20341-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 3287524 INFO (jetty-launcher-20341-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@cbeba48{/solr,null,AVAILABLE}
[junit4] 2> 3287524 INFO (jetty-launcher-20341-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@ab905c2{/solr,null,AVAILABLE}
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@3b109c9a{SSL, (ssl, http/1.1)}{127.0.0.1:46689}
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5b6e5f33{SSL, (ssl, http/1.1)}{127.0.0.1:33670}
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-1) [ ] o.e.j.s.Server Started @3287659ms
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-2) [ ] o.e.j.s.Server Started @3287659ms
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33670}
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46689}
[junit4] 2> 3287525 ERROR (jetty-launcher-20341-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3287525 ERROR (jetty-launcher-20341-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-18T21:18:40.905Z
[junit4] 2> 3287525 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-05-18T21:18:40.905Z
[junit4] 2> 3287526 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3287527 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3287527 INFO (zkConnectionManagerCallback-20344-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3287527 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3287527 INFO (zkConnectionManagerCallback-20345-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3287527 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3287528 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3287528 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 3288211 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3288211 WARN (jetty-launcher-20341-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 3288212 WARN (jetty-launcher-20341-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7ddf644a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3288212 WARN (jetty-launcher-20341-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7ddf644a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3288215 WARN (jetty-launcher-20341-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 3288215 WARN (jetty-launcher-20341-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@262ca0e2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3288215 WARN (jetty-launcher-20341-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@262ca0e2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3288217 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41443/solr
[junit4] 2> 3288218 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3288219 INFO (zkConnectionManagerCallback-20359-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3288219 INFO (jetty-launcher-20341-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3288342 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3288368 INFO (zkConnectionManagerCallback-20361-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3288368 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3288594 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46689_solr
[junit4] 2> 3288595 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.c.Overseer Overseer (id=72818614864445446-127.0.0.1:46689_solr-n_0000000000) starting
[junit4] 2> 3288613 INFO (OverseerStateUpdate-72818614864445446-127.0.0.1:46689_solr-n_0000000000) [n:127.0.0.1:46689_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46689_solr
[junit4] 2> 3288613 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46689_solr
[junit4] 2> 3288613 DEBUG (OverseerAutoScalingTriggerThread-72818614864445446-127.0.0.1:46689_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3288614 INFO (OverseerStateUpdate-72818614864445446-127.0.0.1:46689_solr-n_0000000000) [n:127.0.0.1:46689_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3288614 DEBUG (OverseerAutoScalingTriggerThread-72818614864445446-127.0.0.1:46689_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 3288615 DEBUG (OverseerAutoScalingTriggerThread-72818614864445446-127.0.0.1:46689_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 3288615 DEBUG (OverseerAutoScalingTriggerThread-72818614864445446-127.0.0.1:46689_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 3288616 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 3288616 WARN (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 3288619 DEBUG (OverseerAutoScalingTriggerThread-72818614864445446-127.0.0.1:46689_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:46689_solr]
[junit4] 2> 3288619 DEBUG (OverseerAutoScalingTriggerThread-72818614864445446-127.0.0.1:46689_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3288619 DEBUG (ScheduledTrigger-20366-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 3288619 DEBUG (OverseerAutoScalingTriggerThread-72818614864445446-127.0.0.1:46689_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 3288651 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 3288651 WARN (jetty-launcher-20341-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 3288652 WARN (jetty-launcher-20341-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@20534cb6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3288652 WARN (jetty-launcher-20341-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@20534cb6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3288654 WARN (jetty-launcher-20341-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 3288655 WARN (jetty-launcher-20341-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@e31c3d1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3288655 WARN (jetty-launcher-20341-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@e31c3d1[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 3288657 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41443/solr
[junit4] 2> 3288657 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3288658 INFO (zkConnectionManagerCallback-20377-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3288659 INFO (jetty-launcher-20341-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3288664 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3288716 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46689.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@49f18c38
[junit4] 2> 3288729 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46689.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@49f18c38
[junit4] 2> 3288729 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46689.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@49f18c38
[junit4] 2> 3288731 INFO (jetty-launcher-20341-thread-1) [n:127.0.0.1:46689_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_580FCD4CD2BEA015-001/tempDir-001/node1/.
[junit4] 2> 3288761 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3288762 INFO (zkConnectionManagerCallback-20383-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3288762 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3288767 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 3288771 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33670_solr as DOWN
[junit4] 2> 3288773 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 3288773 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33670_solr
[junit4] 2> 3288774 INFO (zkCallback-20360-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3288774 INFO (zkCallback-20382-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 3288775 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 3288776 WARN (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 3288804 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3288842 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33670.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@49f18c38
[junit4] 2> 3288866 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33670.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@49f18c38
[junit4] 2> 3288867 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33670.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@49f18c38
[junit4] 2> 3288868 INFO (jetty-launcher-20341-thread-2) [n:127.0.0.1:33670_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J2/temp/solr.cloud.autoscaling.IndexSizeTriggerTest_580FCD4CD2BEA015-001/tempDir-001/node2/.
[junit4] 2> 3288886 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 3288887 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3288888 INFO (zkConnectionManagerCallback-20396-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3288888 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3288890 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 3288900 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41443/solr ready
[junit4] 2> 3288947 DEBUG (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10046_solr
[junit4] 2> 3288947 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3288947 DEBUG (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 3288948 DEBUG (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 3288948 INFO (SUITE-IndexSizeTriggerTest-seed#[580FCD4CD2BEA015]-worker) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 3288948 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 3288948 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 3288948 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 3288948 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10046_solr, 127.0.0.1:10047_solr]
[junit4] 2> 3288949 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3288949 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 3288949 DEBUG (ScheduledTrigger-20399-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3288970 DEBUG (ScheduledTrigger-20399-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3288990 DEBUG (ScheduledTrigger-20399-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289011 DEBUG (ScheduledTrigger-20399-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289031 DEBUG (ScheduledTrigger-20399-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289057 DEBUG (ScheduledTrigger-20399-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289089 DEBUG (ScheduledTrigger-20399-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289109 DEBUG (ScheduledTrigger-20399-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289130 DEBUG (ScheduledTrigger-20399-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289154 DEBUG (ScheduledTrigger-20399-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289175 DEBUG (ScheduledTrigger-20399-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289183 INFO (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testValidProperties
[junit4] 2> 3289184 DEBUG (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 3289184 DEBUG (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 3289186 INFO (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.IndexSizeTriggerTest #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 2
[junit4] 2> ## Empty nodes: 2
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections:
[junit4] 2> ## Max replicas per node: 0
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 0
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 3289186 INFO (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3289186 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 3289188 DEBUG (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 3289188 DEBUG (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 3289188 DEBUG (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 3289188 DEBUG (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 3289188 DEBUG (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 3289188 DEBUG (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 3289188 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 3289189 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 3289189 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 3289189 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10046_solr, 127.0.0.1:10047_solr]
[junit4] 2> 3289189 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3289189 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 3289189 DEBUG (ScheduledTrigger-20404-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289198 DEBUG (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 3289198 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 3289199 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3289199 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 3289299 INFO (TEST-IndexSizeTriggerTest.testValidProperties-seed#[580FCD4CD2BEA015]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testValidProperties
[junit4] 2> 3289343 INFO (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testInvalidSplitConfig
[junit4] 2> 3289344 DEBUG (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 3289344 DEBUG (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 3289344 INFO (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.IndexSizeTriggerTest #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 2
[junit4] 2> ## Empty nodes: 2
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections:
[junit4] 2> ## Max replicas per node: 0
[junit4] 2> ## Min replicas per node: 0
[junit4] 2> ## Total replicas: 0
[junit4] 2> ######### Solr op counts ##########
[junit4] 2> ######### Autoscaling event counts ###########
[junit4] 2>
[junit4] 2> 3289344 INFO (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimCloudManager === Restarting OverseerTriggerThread and clearing object cache...
[junit4] 2> 3289344 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 3289345 DEBUG (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 3289345 DEBUG (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 3289345 DEBUG (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 3289345 DEBUG (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 3289345 DEBUG (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 3289345 DEBUG (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 3289346 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 3289346 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 3289346 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 0
[junit4] 2> 3289346 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 3289347 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10046_solr, 127.0.0.1:10047_solr]
[junit4] 2> 3289347 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3289347 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 3289347 DEBUG (ScheduledTrigger-20407-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 3289356 DEBUG (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 4
[junit4] 2> 3289356 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 4
[junit4] 2> 3289370 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3289370 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 4, lastZnodeVersion 4
[junit4] 2> 3289457 INFO (TEST-IndexSizeTriggerTest.testInvalidSplitConfig-seed#[580FCD4CD2BEA015]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testInvalidSplitConfig
[junit4] 2> 3289484 INFO (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[580FCD4CD2BEA015]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMergeIntegration
[junit4] 2> 3289484 DEBUG (simCloudManagerPool-20406-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testMergeIntegration_collection, currentVersion=3
[junit4] 2> 3289484 DEBUG (simCloudManagerPool-20406-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 3289484 DEBUG (simCloudManagerPool-20406-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 3289495 DEBUG (simCloudManagerPool-20406-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 3289495 DEBUG (simCloudManagerPool-20406-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 3289506 DEBUG (simCloudManagerPool-20406-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 3289506 DEBUG (simCloudManagerPool-20406-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 3289506 DEBUG (simCloudManagerPool-20406-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testMergeIntegration_collection / shard1)
[junit4] 2> 3289506 DEBUG (simCloudManagerPool-20406-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testMergeIntegration_collection / shard1 (currentVersion=6): {"core_node1":{
[junit4] 2> "core":"testMergeIntegration_collection_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testMergeIntegration_collection",
[junit4] 2> "node_name":"127.0.0.1:10047_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 3289516 DEBUG (simCloudManagerPool-20406-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 3289517 DEBUG (simCloudManagerPool-20406-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 3289527 DEBUG (simCloudManagerPool-20406-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 3289527 DEBUG (simCloudManagerPool-20406-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 3289527 DEBUG (simCloudManagerPool-20406-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testMergeIntegration_collection / shard2)
[junit4] 2> 3289527 DEBUG (simCloudManagerPool-20406-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testMergeIntegration_collection / shard2 (currentVersion=8): {"core_node3":{
[junit4] 2> "core":"testMergeIntegration_collection_shard2_replica_n3",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testMergeIntegration_collection",
[junit4] 2> "node_name":"127.0.0.1:10047_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 3289527 DEBUG (simCloudManagerPool-20406-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testMergeIntegration_collection, currentVersion=8
[junit4] 2> 3289537 DEBUG (simCloudManagerPool-20406-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 3289538 DEBUG (simCloudManagerPool-20406-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 3289538 DEBUG (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 3289538 DEBUG (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 3289553 DEBUG (simCloudManagerPool-20406-thread-27) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3289554 DEBUG (simCloudManagerPool-20406-thread-27) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 5
[junit4] 2> 3289554 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 5
[junit4] 2> 3289554 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3289555 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 5, lastZnodeVersion 5
[junit4] 2> 3289556 DEBUG (simCloudManagerPool-20406-thread-28) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3289556 DEBUG (simCloudManagerPool-20406-thread-28) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 6
[junit4] 2> 3289556 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 6
[junit4] 2> 3289556 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3289557 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 6, lastZnodeVersion 6
[junit4] 2> 3289557 DEBUG (simCloudManagerPool-20406-thread-29) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3289557 DEBUG (simCloudManagerPool-20406-thread-29) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 7
[junit4] 2> 3289558 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 7
[junit4] 2> 3289558 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3289558 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 7, lastZnodeVersion 7
[junit4] 2> 3289563 DEBUG (simCloudManagerPool-20406-thread-46) [ ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 3289563 DEBUG (simCloudManagerPool-20406-thread-46) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 8
[junit4] 2> 3289563 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 8
[junit4] 2> 3289563 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 3289563 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 8, lastZnodeVersion 8
[junit4] 2> 3289621 DEBUG (ScheduledTrigger-20366-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 3289719 DEBUG (ScheduledTrigger-20407-thread-3) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 3289743 DEBUG (ScheduledTrigger-20407-thread-3) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [index_size_trigger3]
[junit4] 2> 3289743 DEBUG (ScheduledTrigger-20407-thread-3) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 3289743 INFO (ScheduledTrigger-20407-thread-3) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=11767662226849574, stage=STARTED, actionName='null', event={
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}, context={}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"}, message='null'}
[junit4] 2> 3289744 DEBUG (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 3289744 INFO (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=11767662274555574, stage=BEFORE_ACTION, actionName='compute_plan', event={
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}, context={properties.BEFORE_ACTION=[compute_plan], source=index_size_trigger3}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"}, message='null'}
[junit4] 2> 3289744 DEBUG (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}} with context properties: {BEFORE_ACTION=[compute_plan]}
[junit4] 2> 3289745 INFO (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.ComputePlanAction Computed plan empty, remained 0 requested ops to try.
[junit4] 2> 3289745 INFO (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=11767662336614774, stage=AFTER_ACTION, actionName='compute_plan', event={
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}, context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"}, {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}], properties.BEFORE_ACTION=[compute_plan], source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"}, message='null'}
[junit4] 2> 3289746 INFO (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=11767662342464324, stage=BEFORE_ACTION, actionName='execute_plan', event={
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}, context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"}, {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}], properties.BEFORE_ACTION=[compute_plan, execute_plan], source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"}, message='null'}
[junit4] 2> 3289746 DEBUG (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.ExecutePlanAction -- processing event: {
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}} with context properties: {AFTER_ACTION=[compute_plan], unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"}, {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}], BEFORE_ACTION=[compute_plan, execute_plan]}
[junit4] 2> 3289746 INFO (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.ExecutePlanAction No operations to execute for event: {
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 3289746 INFO (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=11767662352179274, stage=AFTER_ACTION, actionName='execute_plan', event={
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}, context={properties.unsupportedOps=[Op{action=MERGESHARDS, hints={COLL_SHARD=[{
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"}, {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}], properties.BEFORE_ACTION=[compute_plan, execute_plan], source=index_size_trigger3, properties.AFTER_ACTION=[compute_plan, execute_plan]}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"}, message='null'}
[junit4] 2> 3289746 DEBUG (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 3289746 INFO (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=11767662365436074, stage=SUCCEEDED, actionName='null', event={
[junit4] 2> "id":"29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767653326706874,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "__start__":1,
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767662194348574,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}, context={}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"}, message='null'}
[junit4] 2> 3289746 DEBUG (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: index_size_trigger3 after 100ms
[junit4] 2> 3289746 DEBUG (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 122 ms for event id=29ce9e4c60d8baTbxfdckhfkzjl9ipahvgl2mrbp
[junit4] 2> 3289748 DEBUG (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 3289748 DEBUG (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 3289891 DEBUG (ScheduledTrigger-20407-thread-4) [ ] o.a.s.c.a.ScheduledTriggers ++++++++ Cooldown inactive - processing event: {
[junit4] 2> "id":"29ce9ff95f9ab4Tbxfdckhfkzjl9ipahvgl2mrbq",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767660524051124,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 3289891 DEBUG (ScheduledTrigger-20407-thread-4) [ ] o.a.s.c.a.ScheduledTriggers Pausing all triggers: [index_size_trigger3]
[junit4] 2> 3289891 DEBUG (ScheduledTrigger-20407-thread-4) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"29ce9ff95f9ab4Tbxfdckhfkzjl9ipahvgl2mrbq",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767660524051124,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767669606115724,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 3289891 INFO (ScheduledTrigger-20407-thread-4) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=11767669614298424, stage=STARTED, actionName='null', event={
[junit4] 2> "id":"29ce9ff95f9ab4Tbxfdckhfkzjl9ipahvgl2mrbq",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767660524051124,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767669606115724,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}, context={}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"}, message='null'}
[junit4] 2> 3289891 DEBUG (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing actions for {
[junit4] 2> "id":"29ce9ff95f9ab4Tbxfdckhfkzjl9ipahvgl2mrbq",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767660524051124,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767669606115724,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}
[junit4] 2> 3289891 INFO (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.IndexSizeTriggerTest =======> CapturedEvent{timestamp=11767669631962674, stage=BEFORE_ACTION, actionName='compute_plan', event={
[junit4] 2> "id":"29ce9ff95f9ab4Tbxfdckhfkzjl9ipahvgl2mrbq",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767660524051124,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767669606115724,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}}, context={properties.BEFORE_ACTION=[compute_plan], source=index_size_trigger3}, config={
[junit4] 2> "trigger":"index_size_trigger3",
[junit4] 2> "stage":[
[junit4] 2> "STARTED",
[junit4] 2> "ABORTED",
[junit4] 2> "SUCCEEDED",
[junit4] 2> "FAILED"],
[junit4] 2> "beforeAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "afterAction":[
[junit4] 2> "compute_plan",
[junit4] 2> "execute_plan"],
[junit4] 2> "class":"org.apache.solr.cloud.autoscaling.IndexSizeTriggerTest$CapturingTriggerListener"}, message='null'}
[junit4] 2> 3289891 DEBUG (AutoscalingActionExecutor-20408-thread-1) [ ] o.a.s.c.a.ComputePlanAction -- processing event: {
[junit4] 2> "id":"29ce9ff95f9ab4Tbxfdckhfkzjl9ipahvgl2mrbq",
[junit4] 2> "source":"index_size_trigger3",
[junit4] 2> "eventTime":11767660524051124,
[junit4] 2> "eventType":"INDEXSIZE",
[junit4] 2> "properties":{
[junit4] 2> "aboveSize":{},
[junit4] 2> "belowSize":{
[junit4] 2> "testMergeIntegration_collection_shard1_replica_n1":"docs=2, bytes=2389",
[junit4] 2> "testMergeIntegration_collection_shard2_replica_n3":"docs=3, bytes=6144"},
[junit4] 2> "_enqueue_time_":11767669606115724,
[junit4] 2> "requestedOps":[{
[junit4] 2> "action":"MERGESHARDS",
[junit4] 2> "hints":{"COLL_SHARD":[
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard1"},
[junit4] 2> {
[junit4] 2> "first":"testMergeIntegration_collection",
[junit4] 2> "second":"shard2"}]}}]}} with context properties: {BEFORE_ACTION=[compute_plan]}
[junit4] 2> 3289892 INFO (TEST-IndexSizeTriggerTest.testMergeIntegration-seed#[580FCD4CD2BEA015]) [ ] o.a.s.c.a.IndexSizeTriggerTest #######################################
[junit4] 2> ############ CLUSTER STATE ############
[junit4] 2> #######################################
[junit4] 2> ## Live nodes: 2
[junit4] 2> ## Empty nodes: 0
[junit4] 2> ## Dead nodes: 0
[junit4] 2> ## Collections:
[junit4] 2> ## * testMergeIntegration_collection
[junit4] 2> ## shardsTotal 2
[junit4] 2> ## shardsState {active=2}
[junit4] 2> ## shardsWithoutLeader 0
[junit4] 2> ## totalReplicas 4
[junit4] 2> ## activeReplicas 4
[junit4] 2> ## inactiveReplicas 0
[junit4] 2> ## totalActiveDocs 5
[junit4] 2> ## bufferedDocs 0
[junit4] 2> ## maxActiveSliceDocs 3
[junit4] 2> ## minActiveSliceDocs 2
[junit4] 2> ## avgActiveSliceDocs 3
[junit4] 2> ## totalInactiveDocs 0
[junit4] 2> ## maxInactiveSliceDocs 0
[junit4] 2> ## minInactiveSliceDocs 0
[junit4] 2> ## avgInactiveSliceDocs NaN
[junit4] 2> ## totalActiveBytes 30,720
[junit4] 2> ## maxActiveSliceBytes 16,384
[junit4] 2> ## minActiveSliceBytes 14,336
[junit4] 2> ## avgActiveSliceBytes 15,360
[junit4] 2> ## totalInactiveBytes 0
[junit4] 2> ## maxInactiveSliceBytes 0
[junit4] 2> ## minInactiveSliceBytes 0
[junit4] 2> ## avgInactiveSliceBytes NaN
[junit4] 2> ## totalActiveDeletedDocs 0
[junit4] 2> DocCollection(testMergeIntegration_collection//clusterstate.json/10)={
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"2",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"2",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "autoCreated":"true",
[junit4] 2> "shards":{
[junit4] 2> "shard2":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"testMergeIntegration_collection_shard2_replica_n3",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":8,
[junit4] 2> "SEARCHER.searcher.deletedDocs":5,
[junit4] 2> "INDEX.sizeInBytes":16384,
[junit4] 2> "node_name":"127.0.0.1:10047_solr",
[junit4] 2> "UPDATE./update.requests":8,
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":1.52587890625E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":3},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testMergeIntegration_collection_shard2_replica_n4",
[junit4] 2> "SEARCHER.searcher.maxDoc":8,
[junit4] 2> "SEARCHER.searcher.deletedDocs":5,
[junit4] 2> "INDEX.sizeInBytes":16384,
[junit4] 2> "node_name":"127.0.0.1:10046_solr",
[junit4] 2> "UPDATE./update.requests":8,
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":1.52587890625E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":3}},
[junit4] 2> "range":"0-7fffffff",
[junit4] 2> "state":"active"},
[junit4] 2> "shard1":{
[junit4] 2> "replicas":{
[junit4] 2> "core_node1":{
[junit4] 2> "core":"testMergeIntegration_collection_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":12,
[junit4] 2> "SEARCHER.searcher.deletedDocs":10,
[junit4] 2> "INDEX.sizeInBytes":14336,
[junit4] 2> "node_name":"127.0.0.1:10047_solr",
[junit4] 2> "UPDATE./update.requests":12,
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":1.33514404296875E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":2},
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testMergeIntegration_collection_shard1_replica_n2",
[junit4] 2> "SEARCHER.searcher.maxDoc":12,
[junit4] 2> "SEARCHER.searcher.deletedDocs":10,
[junit4] 2> "INDEX.sizeInBytes":14336,
[junit4] 2> "node_name":"127.0.0.1:10046_solr",
[junit4] 2> "UPDATE./update.requests":12,
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "INDEX.sizeInGB":1.33514404296875E-5,
[junit4] 2> "SEARCHER.searcher.numDocs":2}},
[junit4] 2> "range":"80000000-ffffffff",
[junit4] 2> "state":"active"}}}
[junit4] 2> #
[...truncated too long message...]
.. (3) -> (2)
[junit4] 2> 84374 INFO (zkCallback-125-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (2)
[junit4] 2> 84397 INFO (coreCloseExecutor-153-thread-1) [n:127.0.0.1:35099_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1_collection.shard2.leader tag=SolrCore@7f64ca39
[junit4] 2> 84397 INFO (coreCloseExecutor-154-thread-1) [n:127.0.0.1:41906_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.collection1_collection.shard1.leader tag=SolrCore@5331c6e4
[junit4] 2> 84399 INFO (coreCloseExecutor-153-thread-1) [n:127.0.0.1:35099_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 84399 INFO (coreCloseExecutor-154-thread-1) [n:127.0.0.1:41906_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 84417 INFO (jetty-closer-139-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72818707538837517-127.0.0.1:44901_solr-n_0000000000) closing
[junit4] 2> 84420 INFO (jetty-closer-139-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 84420 INFO (jetty-closer-139-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7af71463: rootName = solr_41906, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@4954e9cc
[junit4] 2> 84420 INFO (jetty-closer-139-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 84420 INFO (jetty-closer-139-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@aa3953a: rootName = solr_35099, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@76c55e7c
[junit4] 2> 84421 INFO (jetty-closer-139-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@480ff765{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 84424 INFO (jetty-closer-139-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5e3daff6{/solr,null,UNAVAILABLE}
[junit4] 2> 84441 INFO (jetty-closer-139-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 84464 INFO (jetty-closer-139-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 84464 INFO (jetty-closer-139-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61be7de4: rootName = solr_35099, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@1a272880
[junit4] 2> 84464 INFO (jetty-closer-139-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 84464 INFO (jetty-closer-139-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@73bb137d: rootName = solr_41906, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@1a272880
[junit4] 2> 84484 INFO (jetty-closer-139-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 84484 INFO (jetty-closer-139-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4b571917: rootName = solr_41906, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@417bae51
[junit4] 2> 84484 INFO (jetty-closer-139-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 84487 INFO (jetty-closer-139-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 84488 INFO (jetty-closer-139-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1a31ef5d: rootName = solr_35099, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@417bae51
[junit4] 2> 84488 INFO (jetty-closer-139-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 84519 INFO (jetty-closer-139-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@630708fb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 84519 INFO (jetty-closer-139-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7453920{/solr,null,UNAVAILABLE}
[junit4] 2> 84520 INFO (jetty-closer-139-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 84678 INFO (zkCallback-61-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 84819 INFO (jetty-closer-139-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6e124e4b{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 84820 INFO (jetty-closer-139-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7fb6fa85{/solr,null,UNAVAILABLE}
[junit4] 2> 84820 INFO (jetty-closer-139-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 84920 INFO (jetty-closer-139-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@45f4a4af{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 84920 INFO (jetty-closer-139-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@567b574e{/solr,null,UNAVAILABLE}
[junit4] 2> 84921 INFO (jetty-closer-139-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 84922 INFO (SUITE-MathExpressionTest-seed#[B326D5B797172764]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 85128 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/aliases.json
[junit4] 2> 7 /solr/clusterprops.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/collections/collection1_collection/terms/shard1
[junit4] 2> 3 /solr/collections/collection1_collection/terms/shard2
[junit4] 2> 2 /solr/collections/collection1_collection/collectionprops.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/collections/collection1_collection/state.json
[junit4] 2> 7 /solr/clusterstate.json
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 2 /solr/overseer_elect/election/72818707538837515-127.0.0.1:41906_solr-n_0000000001
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 28 /solr/live_nodes
[junit4] 2> 20 /solr/overseer/queue
[junit4] 2> 12 /solr/collections
[junit4] 2> 7 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 85153 INFO (SUITE-MathExpressionTest-seed#[B326D5B797172764]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:42638
[junit4] 2> 85153 INFO (SUITE-MathExpressionTest-seed#[B326D5B797172764]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:42638
[junit4] 2> 85153 INFO (SUITE-MathExpressionTest-seed#[B326D5B797172764]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 42638
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-8.x/solr/build/solr-solrj/test/J0/temp/solr.client.solrj.io.stream.MathExpressionTest_B326D5B797172764-001
[junit4] 2> May 18, 2020 9:43:28 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(Lucene84): {multiDefault=PostingsFormat(name=LuceneFixedGap), price_f=PostingsFormat(name=LuceneFixedGap), intDefault=PostingsFormat(name=MockRandom), test_dt=FST50, price_i=PostingsFormat(name=Direct), test_t=PostingsFormat(name=Direct), col_s=PostingsFormat(name=MockRandom), order_i=PostingsFormat(name=LuceneFixedGap), loc_p_1_d1=FST50, _version_=PostingsFormat(name=MockRandom), loc_p_0_d1=PostingsFormat(name=MockRandom), id=PostingsFormat(name=Direct), text=FST50, miles_i=PostingsFormat(name=MockRandom), timestamp=PostingsFormat(name=LuceneFixedGap)}, docValues:{col_s=DocValuesFormat(name=Asserting), order_i=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Asserting), multiDefault=DocValuesFormat(name=Direct), price_f=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Asserting), test_dt=DocValuesFormat(name=Lucene80), id=DocValuesFormat(name=Lucene80), price_i=DocValuesFormat(name=Lucene80), miles_i=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1455, maxMBSortInHeap=7.338976447416187, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@64ae5f08), locale=ar-TN, timezone=America/Hermosillo
[junit4] 2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 1.8.0_191 (64-bit)/cpus=8,threads=1,free=365400800,total=507510784
[junit4] 2> NOTE: All tests run in this JVM: [NotEvaluatorTest, ArcSineEvaluatorTest, MathExpressionTest]
[junit4] Completed [5/208 (1!)] on J0 in 77.60s, 141 tests, 1 failure <<< FAILURES!
[...truncated 51928 lines...]