lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-master - Build # 2823 - Still Unstable
Date Wed, 26 Sep 2018 18:17:42 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/2823/

3 tests failed.
FAILED:  org.apache.solr.cloud.MultiThreadedOCPTest.test

Error Message:
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer/collection-queue-work

Stack Trace:
org.apache.solr.common.SolrException: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /overseer/collection-queue-work
	at __randomizedtesting.SeedInfo.seed([D041A3E35F39E43D:58159C39F1C589C5]:0)
	at org.apache.solr.cloud.ZkDistributedQueue.<init>(ZkDistributedQueue.java:124)
	at org.apache.solr.cloud.ZkDistributedQueue.<init>(ZkDistributedQueue.java:114)
	at org.apache.solr.cloud.MultiThreadedOCPTest.testTaskExclusivity(MultiThreadedOCPTest.java:155)
	at org.apache.solr.cloud.MultiThreadedOCPTest.test(MultiThreadedOCPTest.java:66)
	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:1742)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1008)
	at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:983)
	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:944)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
	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 /overseer/collection-queue-work
	at org.apache.zookeeper.KeeperException.create(KeeperException.java:130)
	at org.apache.zookeeper.KeeperException.create(KeeperException.java:54)
	at org.apache.zookeeper.ZooKeeper.exists(ZooKeeper.java:1105)
	at org.apache.solr.common.cloud.SolrZkClient.lambda$exists$3(SolrZkClient.java:317)
	at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
	at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:317)
	at org.apache.solr.common.cloud.ZkCmdExecutor.ensureExists(ZkCmdExecutor.java:93)
	at org.apache.solr.common.cloud.ZkCmdExecutor.ensureExists(ZkCmdExecutor.java:78)
	at org.apache.solr.cloud.ZkDistributedQueue.<init>(ZkDistributedQueue.java:122)
	... 44 more


FAILED:  org.apache.solr.cloud.autoscaling.SearchRateTriggerTest.testTrigger

Error Message:
expected:<1> but was:<0>

Stack Trace:
java.lang.AssertionError: expected:<1> but was:<0>
	at __randomizedtesting.SeedInfo.seed([D041A3E35F39E43D:B38A9561C6F69710]: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:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.cloud.autoscaling.SearchRateTriggerTest.testTrigger(SearchRateTriggerTest.java:142)
	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:1742)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:935)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:971)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:985)
	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:944)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:830)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:880)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:891)
	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.cdcr.CdcrBidirectionalTest.testBiDir

Error Message:
Captured an uncaught exception in thread: Thread[id=25204, name=cdcr-replicator-8607-thread-1, state=RUNNABLE, group=TGRP-CdcrBidirectionalTest]

Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=25204, name=cdcr-replicator-8607-thread-1, state=RUNNABLE, group=TGRP-CdcrBidirectionalTest]
Caused by: java.lang.AssertionError: 1612690765596590080 != 1612690765036650496
	at __randomizedtesting.SeedInfo.seed([D041A3E35F39E43D]:0)
	at org.apache.solr.update.CdcrUpdateLog$CdcrLogReader.forwardSeek(CdcrUpdateLog.java:611)
	at org.apache.solr.handler.CdcrReplicator.run(CdcrReplicator.java:125)
	at org.apache.solr.handler.CdcrReplicatorScheduler.lambda$null$0(CdcrReplicatorScheduler.java:81)
	at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:209)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
	at java.lang.Thread.run(Thread.java:748)




Build Log:
[...truncated 13011 lines...]
   [junit4] Suite: org.apache.solr.cloud.autoscaling.SearchRateTriggerTest
   [junit4]   2> 945064 INFO  (SUITE-SearchRateTriggerTest-seed#[D041A3E35F39E43D]-worker) [    ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> Creating dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/init-core-data-001
   [junit4]   2> 945085 WARN  (SUITE-SearchRateTriggerTest-seed#[D041A3E35F39E43D]-worker) [    ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=2 numCloses=2
   [junit4]   2> 945085 INFO  (SUITE-SearchRateTriggerTest-seed#[D041A3E35F39E43D]-worker) [    ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
   [junit4]   2> 945087 INFO  (SUITE-SearchRateTriggerTest-seed#[D041A3E35F39E43D]-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> 945087 INFO  (SUITE-SearchRateTriggerTest-seed#[D041A3E35F39E43D]-worker) [    ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001
   [junit4]   2> 945087 INFO  (SUITE-SearchRateTriggerTest-seed#[D041A3E35F39E43D]-worker) [    ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 945088 INFO  (Thread-1046) [    ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
   [junit4]   2> 945088 INFO  (Thread-1046) [    ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 945100 ERROR (Thread-1046) [    ] 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> 945188 INFO  (SUITE-SearchRateTriggerTest-seed#[D041A3E35F39E43D]-worker) [    ] o.a.s.c.ZkTestServer start zk server on port:43527
   [junit4]   2> 945210 INFO  (zkConnectionManagerCallback-1134-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 945266 INFO  (jetty-launcher-1131-thread-1) [    ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 945268 INFO  (jetty-launcher-1131-thread-2) [    ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 945270 INFO  (jetty-launcher-1131-thread-3) [    ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 945292 INFO  (jetty-launcher-1131-thread-4) [    ] o.e.j.s.Server jetty-9.4.11.v20180605; built: 2018-06-05T18:24:03.829Z; git: d5fc0523cfa96bfebfbda19606cad384d772f04c; jvm 1.8.0_172-b11
   [junit4]   2> 945363 INFO  (jetty-launcher-1131-thread-2) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 945363 INFO  (jetty-launcher-1131-thread-2) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 945363 INFO  (jetty-launcher-1131-thread-2) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 945364 INFO  (jetty-launcher-1131-thread-3) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 945364 INFO  (jetty-launcher-1131-thread-3) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 945364 INFO  (jetty-launcher-1131-thread-3) [    ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 945364 INFO  (jetty-launcher-1131-thread-3) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@766ba8e4{/solr,null,AVAILABLE}
   [junit4]   2> 945365 INFO  (jetty-launcher-1131-thread-3) [    ] o.e.j.s.AbstractConnector Started ServerConnector@2ba44687{SSL,[ssl, http/1.1]}{127.0.0.1:33889}
   [junit4]   2> 945365 INFO  (jetty-launcher-1131-thread-3) [    ] o.e.j.s.Server Started @945763ms
   [junit4]   2> 945365 INFO  (jetty-launcher-1131-thread-3) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33889}
   [junit4]   2> 945366 ERROR (jetty-launcher-1131-thread-3) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 945366 INFO  (jetty-launcher-1131-thread-3) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 945366 INFO  (jetty-launcher-1131-thread-3) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.0.0
   [junit4]   2> 945366 INFO  (jetty-launcher-1131-thread-3) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 945366 INFO  (jetty-launcher-1131-thread-3) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 945366 INFO  (jetty-launcher-1131-thread-3) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2018-09-26T16:16:57.317Z
   [junit4]   2> 945499 INFO  (jetty-launcher-1131-thread-4) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 945499 INFO  (jetty-launcher-1131-thread-4) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 945499 INFO  (jetty-launcher-1131-thread-4) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 945515 INFO  (jetty-launcher-1131-thread-4) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@13d4fdd5{/solr,null,AVAILABLE}
   [junit4]   2> 945572 INFO  (jetty-launcher-1131-thread-1) [    ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 945573 INFO  (jetty-launcher-1131-thread-1) [    ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 945573 INFO  (jetty-launcher-1131-thread-2) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@46168be3{/solr,null,AVAILABLE}
   [junit4]   2> 945573 INFO  (jetty-launcher-1131-thread-1) [    ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 945573 INFO  (jetty-launcher-1131-thread-1) [    ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3879a66b{/solr,null,AVAILABLE}
   [junit4]   2> 945573 INFO  (jetty-launcher-1131-thread-2) [    ] o.e.j.s.AbstractConnector Started ServerConnector@20fbc141{SSL,[ssl, http/1.1]}{127.0.0.1:42290}
   [junit4]   2> 945573 INFO  (jetty-launcher-1131-thread-2) [    ] o.e.j.s.Server Started @945971ms
   [junit4]   2> 945573 INFO  (jetty-launcher-1131-thread-2) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42290}
   [junit4]   2> 945573 INFO  (jetty-launcher-1131-thread-1) [    ] o.e.j.s.AbstractConnector Started ServerConnector@6da6a36{SSL,[ssl, http/1.1]}{127.0.0.1:34374}
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-1) [    ] o.e.j.s.Server Started @945971ms
   [junit4]   2> 945574 ERROR (jetty-launcher-1131-thread-2) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-1) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34374}
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-2) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-2) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.0.0
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-2) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-2) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 945574 ERROR (jetty-launcher-1131-thread-1) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-1) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-2) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2018-09-26T16:16:57.525Z
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-1) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.0.0
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-1) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-1) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 945574 INFO  (jetty-launcher-1131-thread-1) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2018-09-26T16:16:57.525Z
   [junit4]   2> 945591 INFO  (zkConnectionManagerCallback-1138-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 945591 INFO  (jetty-launcher-1131-thread-4) [    ] o.e.j.s.AbstractConnector Started ServerConnector@3a880e8f{SSL,[ssl, http/1.1]}{127.0.0.1:32974}
   [junit4]   2> 945591 INFO  (jetty-launcher-1131-thread-4) [    ] o.e.j.s.Server Started @945989ms
   [junit4]   2> 945591 INFO  (jetty-launcher-1131-thread-4) [    ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=32974}
   [junit4]   2> 945592 ERROR (jetty-launcher-1131-thread-4) [    ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 945592 INFO  (jetty-launcher-1131-thread-4) [    ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 945592 INFO  (jetty-launcher-1131-thread-4) [    ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr™ version 8.0.0
   [junit4]   2> 945592 INFO  (jetty-launcher-1131-thread-4) [    ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 945592 INFO  (jetty-launcher-1131-thread-4) [    ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: null
   [junit4]   2> 945592 INFO  (jetty-launcher-1131-thread-4) [    ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2018-09-26T16:16:57.543Z
   [junit4]   2> 945592 INFO  (zkConnectionManagerCallback-1136-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 945597 INFO  (jetty-launcher-1131-thread-2) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 945597 INFO  (jetty-launcher-1131-thread-3) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 945664 INFO  (zkConnectionManagerCallback-1140-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 945673 INFO  (zkConnectionManagerCallback-1142-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 945678 INFO  (jetty-launcher-1131-thread-1) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 945736 INFO  (jetty-launcher-1131-thread-4) [    ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 946446 INFO  (jetty-launcher-1131-thread-1) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43527/solr
   [junit4]   2> 946470 INFO  (zkConnectionManagerCallback-1146-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 946653 INFO  (zkConnectionManagerCallback-1148-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 946703 INFO  (jetty-launcher-1131-thread-4) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43527/solr
   [junit4]   2> 946811 INFO  (jetty-launcher-1131-thread-3) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43527/solr
   [junit4]   2> 946816 INFO  (zkConnectionManagerCallback-1154-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 946818 INFO  (jetty-launcher-1131-thread-2) [    ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:43527/solr
   [junit4]   2> 946839 INFO  (zkConnectionManagerCallback-1158-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 946853 INFO  (zkConnectionManagerCallback-1162-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 946871 WARN  (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [    ] o.a.z.s.NIOServerCnxn Unable to read additional data from client sessionid 0x104baedd5420008, likely client has closed socket
   [junit4]   2> 946889 INFO  (zkConnectionManagerCallback-1164-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 946941 INFO  (zkConnectionManagerCallback-1166-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 946942 INFO  (zkConnectionManagerCallback-1168-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 946972 INFO  (jetty-launcher-1131-thread-4) [n:127.0.0.1:32974_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32974_solr
   [junit4]   2> 946974 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:33889_solr
   [junit4]   2> 947027 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.c.Overseer Overseer (id=73389024592658442-127.0.0.1:33889_solr-n_0000000000) starting
   [junit4]   2> 947128 INFO  (zkCallback-1165-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 947130 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 947157 INFO  (zkCallback-1163-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 947188 INFO  (zkConnectionManagerCallback-1179-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 947188 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 947188 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42290_solr
   [junit4]   2> 947202 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 947203 INFO  (zkConnectionManagerCallback-1184-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 947203 INFO  (zkCallback-1165-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 947203 INFO  (zkCallback-1163-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 947204 INFO  (jetty-launcher-1131-thread-4) [n:127.0.0.1:32974_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 947207 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43527/solr ready
   [junit4]   2> 947213 INFO  (jetty-launcher-1131-thread-4) [n:127.0.0.1:32974_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43527/solr ready
   [junit4]   2> 947226 INFO  (OverseerStateUpdate-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:33889_solr
   [junit4]   2> 947227 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 947227 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33889_solr
   [junit4]   2> 947229 INFO  (jetty-launcher-1131-thread-4) [n:127.0.0.1:32974_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 947264 INFO  (zkCallback-1167-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
   [junit4]   2> 947301 INFO  (zkCallback-1183-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 947301 INFO  (zkCallback-1163-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 947340 INFO  (zkCallback-1165-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 947345 INFO  (zkCallback-1178-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 947390 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 947471 INFO  (zkConnectionManagerCallback-1190-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 947474 INFO  (jetty-launcher-1131-thread-4) [n:127.0.0.1:32974_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32974.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947513 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 947514 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43527/solr ready
   [junit4]   2> 947515 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 947517 INFO  (jetty-launcher-1131-thread-4) [n:127.0.0.1:32974_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32974.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947517 INFO  (jetty-launcher-1131-thread-4) [n:127.0.0.1:32974_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32974.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947518 INFO  (jetty-launcher-1131-thread-4) [n:127.0.0.1:32974_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node4/.
   [junit4]   2> 947575 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42290.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947647 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33889.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947661 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42290.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947661 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42290.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947663 INFO  (jetty-launcher-1131-thread-2) [n:127.0.0.1:42290_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node2/.
   [junit4]   2> 947679 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33889.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947679 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33889.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947680 INFO  (jetty-launcher-1131-thread-3) [n:127.0.0.1:33889_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node3/.
   [junit4]   2> 947703 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
   [junit4]   2> 947743 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 947744 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34374_solr
   [junit4]   2> 947745 INFO  (zkCallback-1178-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 947745 INFO  (zkCallback-1163-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 947745 INFO  (zkCallback-1165-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 947766 INFO  (zkCallback-1167-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 947778 INFO  (zkCallback-1183-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 947821 INFO  (zkCallback-1189-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 947823 INFO  (zkCallback-1147-thread-1) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 947859 INFO  (zkConnectionManagerCallback-1197-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 947860 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 947861 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43527/solr ready
   [junit4]   2> 947861 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 947925 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34374.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947932 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34374.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947945 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34374.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 947946 INFO  (jetty-launcher-1131-thread-1) [n:127.0.0.1:34374_solr    ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node1/.
   [junit4]   2> 948030 INFO  (zkConnectionManagerCallback-1200-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 948034 INFO  (zkConnectionManagerCallback-1205-thread-1) [    ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 948035 INFO  (SUITE-SearchRateTriggerTest-seed#[D041A3E35F39E43D]-worker) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 948036 INFO  (SUITE-SearchRateTriggerTest-seed#[D041A3E35F39E43D]-worker) [    ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:43527/solr ready
   [junit4]   2> 948848 INFO  (TEST-SearchRateTriggerTest.testTrigger-seed#[D041A3E35F39E43D]) [    ] o.a.s.SolrTestCaseJ4 ###Starting testTrigger
   [junit4]   2> 948874 INFO  (TEST-SearchRateTriggerTest.testTrigger-seed#[D041A3E35F39E43D]) [    ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 949171 INFO  (qtp1316419848-5576) [n:127.0.0.1:34374_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=1&name=SearchRateTriggerTest-collection1&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 949190 INFO  (OverseerThreadFactory-2374-thread-1-processing-n:127.0.0.1:33889_solr) [n:127.0.0.1:33889_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection SearchRateTriggerTest-collection1
   [junit4]   2> 949497 INFO  (OverseerStateUpdate-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"SearchRateTriggerTest-collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"SearchRateTriggerTest-collection1_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:32974/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 949528 INFO  (OverseerStateUpdate-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"SearchRateTriggerTest-collection1",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"SearchRateTriggerTest-collection1_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:42290/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 949530 INFO  (OverseerStateUpdate-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"SearchRateTriggerTest-collection1",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"SearchRateTriggerTest-collection1_shard2_replica_n4",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:33889/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 949545 INFO  (OverseerStateUpdate-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"SearchRateTriggerTest-collection1",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"SearchRateTriggerTest-collection1_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:34374/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 950526 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr    x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection1_shard2_replica_n6&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 950527 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr    x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 950527 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr    x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
   [junit4]   2> 950693 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr    x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection1_shard1_replica_n2&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 950771 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr    x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection1_shard2_replica_n4&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 951680 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 951724 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.s.IndexSchema [SearchRateTriggerTest-collection1_shard2_replica_n6] Schema name=minimal
   [junit4]   2> 951738 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 951739 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'SearchRateTriggerTest-collection1_shard2_replica_n6' using configuration from collection SearchRateTriggerTest-collection1, trusted=true
   [junit4]   2> 951739 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34374.solr.core.SearchRateTriggerTest-collection1.shard2.replica_n6' (registry 'solr.core.SearchRateTriggerTest-collection1.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 951740 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.SolrCore [[SearchRateTriggerTest-collection1_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node1/SearchRateTriggerTest-collection1_shard2_replica_n6], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node1/./SearchRateTriggerTest-collection1_shard2_replica_n6/data/]
   [junit4]   2> 952160 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 952198 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.s.IndexSchema [SearchRateTriggerTest-collection1_shard1_replica_n1] Schema name=minimal
   [junit4]   2> 952214 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 952214 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'SearchRateTriggerTest-collection1_shard1_replica_n1' using configuration from collection SearchRateTriggerTest-collection1, trusted=true
   [junit4]   2> 952214 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32974.solr.core.SearchRateTriggerTest-collection1.shard1.replica_n1' (registry 'solr.core.SearchRateTriggerTest-collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 952214 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.SolrCore [[SearchRateTriggerTest-collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node4/SearchRateTriggerTest-collection1_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node4/./SearchRateTriggerTest-collection1_shard1_replica_n1/data/]
   [junit4]   2> 952254 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 952295 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 952397 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.s.IndexSchema [SearchRateTriggerTest-collection1_shard1_replica_n2] Schema name=minimal
   [junit4]   2> 952408 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.s.IndexSchema [SearchRateTriggerTest-collection1_shard2_replica_n4] Schema name=minimal
   [junit4]   2> 952410 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 952410 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.c.CoreContainer Creating SolrCore 'SearchRateTriggerTest-collection1_shard2_replica_n4' using configuration from collection SearchRateTriggerTest-collection1, trusted=true
   [junit4]   2> 952427 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 952427 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 952429 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 952429 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 952432 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 952433 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'SearchRateTriggerTest-collection1_shard1_replica_n2' using configuration from collection SearchRateTriggerTest-collection1, trusted=true
   [junit4]   2> 952433 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42290.solr.core.SearchRateTriggerTest-collection1.shard1.replica_n2' (registry 'solr.core.SearchRateTriggerTest-collection1.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 952433 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.c.SolrCore [[SearchRateTriggerTest-collection1_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node2/SearchRateTriggerTest-collection1_shard1_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node2/./SearchRateTriggerTest-collection1_shard1_replica_n2/data/]
   [junit4]   2> 952444 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.s.SolrIndexSearcher Opening [Searcher@321641f4[SearchRateTriggerTest-collection1_shard2_replica_n6] main]
   [junit4]   2> 952444 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33889.solr.core.SearchRateTriggerTest-collection1.shard2.replica_n4' (registry 'solr.core.SearchRateTriggerTest-collection1.shard2.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 952444 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.c.SolrCore [[SearchRateTriggerTest-collection1_shard2_replica_n4] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node3/SearchRateTriggerTest-collection1_shard2_replica_n4], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node3/./SearchRateTriggerTest-collection1_shard2_replica_n4/data/]
   [junit4]   2> 952480 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 952481 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 952482 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 952482 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1612687474093457408
   [junit4]   2> 952523 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.ZkShardTerms Successful update of terms at /collections/SearchRateTriggerTest-collection1/terms/shard2 to Terms{values={core_node8=0}, version=0}
   [junit4]   2> 952544 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard2: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 952574 INFO  (searcherExecutor-2389-thread-1-processing-n:127.0.0.1:34374_solr x:SearchRateTriggerTest-collection1_shard2_replica_n6 c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.SolrCore [SearchRateTriggerTest-collection1_shard2_replica_n6] Registered new searcher Searcher@321641f4[SearchRateTriggerTest-collection1_shard2_replica_n6] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 953088 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 953088 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 953122 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 953122 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 953123 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 953123 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 953138 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 953138 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 953140 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@143f2159[SearchRateTriggerTest-collection1_shard1_replica_n1] main]
   [junit4]   2> 953125 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.s.SolrIndexSearcher Opening [Searcher@40918f7[SearchRateTriggerTest-collection1_shard2_replica_n4] main]
   [junit4]   2> 953159 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 953159 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 953160 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 953160 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 953161 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 953161 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 953161 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1612687474805440512
   [junit4]   2> 953161 INFO  (searcherExecutor-2391-thread-1-processing-n:127.0.0.1:32974_solr x:SearchRateTriggerTest-collection1_shard1_replica_n1 c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.SolrCore [SearchRateTriggerTest-collection1_shard1_replica_n1] Registered new searcher Searcher@143f2159[SearchRateTriggerTest-collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 953161 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1612687474805440512
   [junit4]   2> 953174 INFO  (searcherExecutor-2392-thread-1-processing-n:127.0.0.1:33889_solr x:SearchRateTriggerTest-collection1_shard2_replica_n4 c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.c.SolrCore [SearchRateTriggerTest-collection1_shard2_replica_n4] Registered new searcher Searcher@40918f7[SearchRateTriggerTest-collection1_shard2_replica_n4] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 953411 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.c.ZkShardTerms Successful update of terms at /collections/SearchRateTriggerTest-collection1/terms/shard2 to Terms{values={core_node7=0, core_node8=0}, version=1}
   [junit4]   2> 953431 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
   [junit4]   2> 953431 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
   [junit4]   2> 953432 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 953432 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 953446 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.s.SolrIndexSearcher Opening [Searcher@7f2c8454[SearchRateTriggerTest-collection1_shard1_replica_n2] main]
   [junit4]   2> 953448 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
   [junit4]   2> 953448 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
   [junit4]   2> 953449 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
   [junit4]   2> 953449 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1612687475107430400
   [junit4]   2> 953462 INFO  (searcherExecutor-2396-thread-1-processing-n:127.0.0.1:42290_solr x:SearchRateTriggerTest-collection1_shard1_replica_n2 c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.c.SolrCore [SearchRateTriggerTest-collection1_shard1_replica_n2] Registered new searcher Searcher@7f2c8454[SearchRateTriggerTest-collection1_shard1_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
   [junit4]   2> 953463 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.ZkShardTerms Successful update of terms at /collections/SearchRateTriggerTest-collection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
   [junit4]   2> 953485 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Waiting until we see more replicas up for shard shard1: total=2 found=1 timeoutin=9999ms
   [junit4]   2> 953500 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.c.ZkShardTerms Successful update of terms at /collections/SearchRateTriggerTest-collection1/terms/shard1 to Terms{values={core_node3=0, core_node5=0}, version=1}
   [junit4]   2> 953584 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 953584 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 953584 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:34374/solr/SearchRateTriggerTest-collection1_shard2_replica_n6/
   [junit4]   2> 953601 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.u.PeerSync PeerSync: core=SearchRateTriggerTest-collection1_shard2_replica_n6 url=https://127.0.0.1:34374/solr START replicas=[https://127.0.0.1:33889/solr/SearchRateTriggerTest-collection1_shard2_replica_n4/] nUpdates=100
   [junit4]   2> 953621 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.u.PeerSync PeerSync: core=SearchRateTriggerTest-collection1_shard2_replica_n6 url=https://127.0.0.1:34374/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 953806 INFO  (qtp1557152667-5594) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.c.S.Request [SearchRateTriggerTest-collection1_shard2_replica_n4]  webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 953807 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 953807 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 953807 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 953822 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:34374/solr/SearchRateTriggerTest-collection1_shard2_replica_n6/ shard2
   [junit4]   2> 953924 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 953937 INFO  (qtp1316419848-5612) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection1_shard2_replica_n6] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection1_shard2_replica_n6&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=3411
   [junit4]   2> 953986 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
   [junit4]   2> 953986 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
   [junit4]   2> 953986 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:32974/solr/SearchRateTriggerTest-collection1_shard1_replica_n1/
   [junit4]   2> 953986 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=SearchRateTriggerTest-collection1_shard1_replica_n1 url=https://127.0.0.1:32974/solr START replicas=[https://127.0.0.1:42290/solr/SearchRateTriggerTest-collection1_shard1_replica_n2/] nUpdates=100
   [junit4]   2> 953987 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.u.PeerSync PeerSync: core=SearchRateTriggerTest-collection1_shard1_replica_n1 url=https://127.0.0.1:32974/solr DONE.  We have no versions.  sync failed.
   [junit4]   2> 954108 INFO  (zkCallback-1147-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state.json] for collection [SearchRateTriggerTest-collection1] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 954275 INFO  (qtp364771888-5584) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.c.S.Request [SearchRateTriggerTest-collection1_shard1_replica_n2]  webapp=/solr path=/get params={distrib=false&qt=/get&fingerprint=false&getVersions=100&wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 954276 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.SyncStrategy Leader's attempt to sync with shard failed, moving to the next candidate
   [junit4]   2> 954276 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext We failed sync, but we have no versions - we can't sync in that case - we were active before, so become leader anyway
   [junit4]   2> 954276 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
   [junit4]   2> 954293 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:32974/solr/SearchRateTriggerTest-collection1_shard1_replica_n1/ shard1
   [junit4]   2> 954404 INFO  (zkCallback-1147-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state.json] for collection [SearchRateTriggerTest-collection1] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 954423 INFO  (qtp1557152667-5597) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection1 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection1_shard2_replica_n4] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection1_shard2_replica_n4&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=3651
   [junit4]   2> 954455 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
   [junit4]   2> 954473 INFO  (qtp1318641088-5605) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection1_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=3946
   [junit4]   2> 954531 INFO  (qtp364771888-5587) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection1 s:shard1 r:core_node5 x:SearchRateTriggerTest-collection1_shard1_replica_n2] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection1_shard1_replica_n2&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=3839
   [junit4]   2> 954581 INFO  (qtp1316419848-5576) [n:127.0.0.1:34374_solr    ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
   [junit4]   2> 954667 INFO  (zkCallback-1147-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state.json] for collection [SearchRateTriggerTest-collection1] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 954668 INFO  (zkCallback-1167-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state.json] for collection [SearchRateTriggerTest-collection1] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 954668 INFO  (zkCallback-1163-thread-2) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state.json] for collection [SearchRateTriggerTest-collection1] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 954668 INFO  (zkCallback-1165-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/SearchRateTriggerTest-collection1/state.json] for collection [SearchRateTriggerTest-collection1] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 955439 INFO  (OverseerCollectionConfigSetProcessor-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist.  Requestor may have disconnected from ZooKeeper
   [junit4]   2> 955592 INFO  (qtp1316419848-5576) [n:127.0.0.1:34374_solr    ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&maxShardsPerNode=1&name=SearchRateTriggerTest-collection1&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=6438
   [junit4]   2> 955598 INFO  (qtp1316419848-5580) [n:127.0.0.1:34374_solr    ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&maxShardsPerNode=1&name=SearchRateTriggerTest-collection2&nrtReplicas=2&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
   [junit4]   2> 955679 INFO  (OverseerThreadFactory-2374-thread-2-processing-n:127.0.0.1:33889_solr) [n:127.0.0.1:33889_solr    ] o.a.s.c.a.c.CreateCollectionCmd Create collection SearchRateTriggerTest-collection2
   [junit4]   2> 956026 INFO  (OverseerStateUpdate-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"SearchRateTriggerTest-collection2",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"SearchRateTriggerTest-collection2_shard1_replica_n1",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:34374/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 956076 INFO  (OverseerStateUpdate-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"SearchRateTriggerTest-collection2",
   [junit4]   2>   "shard":"shard1",
   [junit4]   2>   "core":"SearchRateTriggerTest-collection2_shard1_replica_n2",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:42290/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 956223 INFO  (OverseerStateUpdate-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"SearchRateTriggerTest-collection2",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"SearchRateTriggerTest-collection2_shard2_replica_n5",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:33889/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 956225 INFO  (OverseerStateUpdate-73389024592658442-127.0.0.1:33889_solr-n_0000000000) [n:127.0.0.1:33889_solr    ] o.a.s.c.o.SliceMutator createReplica() {
   [junit4]   2>   "operation":"ADDREPLICA",
   [junit4]   2>   "collection":"SearchRateTriggerTest-collection2",
   [junit4]   2>   "shard":"shard2",
   [junit4]   2>   "core":"SearchRateTriggerTest-collection2_shard2_replica_n6",
   [junit4]   2>   "state":"down",
   [junit4]   2>   "base_url":"https://127.0.0.1:32974/solr",
   [junit4]   2>   "type":"NRT",
   [junit4]   2>   "waitForFinalState":"false"} 
   [junit4]   2> 956485 INFO  (qtp1557152667-5599) [n:127.0.0.1:33889_solr    x:SearchRateTriggerTest-collection2_shard2_replica_n5] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection2_shard2_replica_n5&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection2&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 956546 INFO  (qtp1316419848-5578) [n:127.0.0.1:34374_solr    x:SearchRateTriggerTest-collection2_shard1_replica_n1] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection2_shard1_replica_n1&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection2&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 956654 INFO  (qtp1318641088-5607) [n:127.0.0.1:32974_solr    x:SearchRateTriggerTest-collection2_shard2_replica_n6] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection2_shard2_replica_n6&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection2&shard=shard2&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 956655 INFO  (qtp364771888-5585) [n:127.0.0.1:42290_solr    x:SearchRateTriggerTest-collection2_shard1_replica_n2] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=SearchRateTriggerTest-collection2_shard1_replica_n2&action=CREATE&numShards=2&collection=SearchRateTriggerTest-collection2&shard=shard1&wt=javabin&version=2&replicaType=NRT
   [junit4]   2> 956854 INFO  (zkCallback-1165-thread-1) [    ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/SearchRateTriggerTest-collection2/state.json] for collection [SearchRateTriggerTest-collection2] has occurred - updating... (live nodes size: [4])
   [junit4]   2> 957903 INFO  (qtp1557152667-5599) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection2_shard2_replica_n5] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 957957 INFO  (qtp1557152667-5599) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection2_shard2_replica_n5] o.a.s.s.IndexSchema [SearchRateTriggerTest-collection2_shard2_replica_n5] Schema name=minimal
   [junit4]   2> 957959 INFO  (qtp1557152667-5599) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection2_shard2_replica_n5] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 957959 INFO  (qtp1557152667-5599) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection2_shard2_replica_n5] o.a.s.c.CoreContainer Creating SolrCore 'SearchRateTriggerTest-collection2_shard2_replica_n5' using configuration from collection SearchRateTriggerTest-collection2, trusted=true
   [junit4]   2> 957960 INFO  (qtp1557152667-5599) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection2_shard2_replica_n5] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33889.solr.core.SearchRateTriggerTest-collection2.shard2.replica_n5' (registry 'solr.core.SearchRateTriggerTest-collection2.shard2.replica_n5') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 957996 INFO  (qtp1557152667-5599) [n:127.0.0.1:33889_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node7 x:SearchRateTriggerTest-collection2_shard2_replica_n5] o.a.s.c.SolrCore [[SearchRateTriggerTest-collection2_shard2_replica_n5] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node3/SearchRateTriggerTest-collection2_shard2_replica_n5], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node3/./SearchRateTriggerTest-collection2_shard2_replica_n5/data/]
   [junit4]   2> 958007 INFO  (qtp1318641088-5607) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection2_shard2_replica_n6] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 958012 INFO  (qtp364771888-5585) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection2 s:shard1 r:core_node4 x:SearchRateTriggerTest-collection2_shard1_replica_n2] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 958096 INFO  (qtp1318641088-5607) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection2_shard2_replica_n6] o.a.s.s.IndexSchema [SearchRateTriggerTest-collection2_shard2_replica_n6] Schema name=minimal
   [junit4]   2> 958098 INFO  (qtp1318641088-5607) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection2_shard2_replica_n6] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 958098 INFO  (qtp1318641088-5607) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection2_shard2_replica_n6] o.a.s.c.CoreContainer Creating SolrCore 'SearchRateTriggerTest-collection2_shard2_replica_n6' using configuration from collection SearchRateTriggerTest-collection2, trusted=true
   [junit4]   2> 958131 INFO  (qtp1318641088-5607) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection2_shard2_replica_n6] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_32974.solr.core.SearchRateTriggerTest-collection2.shard2.replica_n6' (registry 'solr.core.SearchRateTriggerTest-collection2.shard2.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 958132 INFO  (qtp1318641088-5607) [n:127.0.0.1:32974_solr c:SearchRateTriggerTest-collection2 s:shard2 r:core_node8 x:SearchRateTriggerTest-collection2_shard2_replica_n6] o.a.s.c.SolrCore [[SearchRateTriggerTest-collection2_shard2_replica_n6] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node4/SearchRateTriggerTest-collection2_shard2_replica_n6], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node4/./SearchRateTriggerTest-collection2_shard2_replica_n6/data/]
   [junit4]   2> 958139 INFO  (qtp364771888-5585) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection2 s:shard1 r:core_node4 x:SearchRateTriggerTest-collection2_shard1_replica_n2] o.a.s.s.IndexSchema [SearchRateTriggerTest-collection2_shard1_replica_n2] Schema name=minimal
   [junit4]   2> 958141 INFO  (qtp364771888-5585) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection2 s:shard1 r:core_node4 x:SearchRateTriggerTest-collection2_shard1_replica_n2] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
   [junit4]   2> 958141 INFO  (qtp364771888-5585) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection2 s:shard1 r:core_node4 x:SearchRateTriggerTest-collection2_shard1_replica_n2] o.a.s.c.CoreContainer Creating SolrCore 'SearchRateTriggerTest-collection2_shard1_replica_n2' using configuration from collection SearchRateTriggerTest-collection2, trusted=true
   [junit4]   2> 958142 INFO  (qtp364771888-5585) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection2 s:shard1 r:core_node4 x:SearchRateTriggerTest-collection2_shard1_replica_n2] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42290.solr.core.SearchRateTriggerTest-collection2.shard1.replica_n2' (registry 'solr.core.SearchRateTriggerTest-collection2.shard1.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@45326c7d
   [junit4]   2> 958142 INFO  (qtp364771888-5585) [n:127.0.0.1:42290_solr c:SearchRateTriggerTest-collection2 s:shard1 r:core_node4 x:SearchRateTriggerTest-collection2_shard1_replica_n2] o.a.s.c.SolrCore [[SearchRateTriggerTest-collection2_shard1_replica_n2] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node2/SearchRateTriggerTest-collection2_shard1_replica_n2], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J1/temp/solr.cloud.autoscaling.SearchRateTriggerTest_D041A3E35F39E43D-001/tempDir-001/node2/./SearchRateTriggerTest-collection2_shard1_replica_n2/data/]
   [junit4]   2> 958250 INFO  (qtp1316419848-5578) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection2_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.0.0
   [junit4]   2> 958412 INFO  (qtp1316419848-5578) [n:127.0.0.1:34374_solr c:SearchRateTriggerTest-collection2 s:shard1 r:core_node3 x:SearchRateTriggerTest-collection2_shard1_replica_n1] o

[...truncated too long message...]

y-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

ivy-availability-check:
[loadresource] Do not set property disallowed.ivy.jars.list as its length is 0.

-ivy-fail-disallowed-ivy-version:

ivy-fail:

ivy-fail:

ivy-configure:
[ivy:configure] :: loading settings :: file = /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/lucene/top-level-ivy-settings.xml

resolve:

jar-checksums:
    [mkdir] Created dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null2089396744
     [copy] Copying 238 files to /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null2089396744
   [delete] Deleting directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/null2089396744

check-working-copy:
[ivy:cachepath] :: resolving dependencies :: org.eclipse.jgit#org.eclipse.jgit-caller;working
[ivy:cachepath] 	confs: [default]
[ivy:cachepath] 	found org.eclipse.jgit#org.eclipse.jgit;4.6.0.201612231935-r in public
[ivy:cachepath] 	found com.jcraft#jsch;0.1.53 in public
[ivy:cachepath] 	found com.googlecode.javaewah#JavaEWAH;1.1.6 in public
[ivy:cachepath] 	found org.apache.httpcomponents#httpclient;4.3.6 in public
[ivy:cachepath] 	found org.apache.httpcomponents#httpcore;4.3.3 in public
[ivy:cachepath] 	found commons-logging#commons-logging;1.1.3 in public
[ivy:cachepath] 	found commons-codec#commons-codec;1.6 in public
[ivy:cachepath] 	found org.slf4j#slf4j-api;1.7.2 in public
[ivy:cachepath] :: resolution report :: resolve 136ms :: artifacts dl 17ms
	---------------------------------------------------------------------
	|                  |            modules            ||   artifacts   |
	|       conf       | number| search|dwnlded|evicted|| number|dwnlded|
	---------------------------------------------------------------------
	|      default     |   8   |   0   |   0   |   0   ||   8   |   0   |
	---------------------------------------------------------------------
[wc-checker] Initializing working copy...
[wc-checker] SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
[wc-checker] SLF4J: Defaulting to no-operation (NOP) logger implementation
[wc-checker] SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[wc-checker] Checking working copy status...

-jenkins-base:

BUILD SUCCESSFUL
Total time: 218 minutes 42 seconds
Archiving artifacts
java.lang.InterruptedException: no matches found within 10000
	at hudson.FilePath$34.hasMatch(FilePath.java:2678)
	at hudson.FilePath$34.invoke(FilePath.java:2557)
	at hudson.FilePath$34.invoke(FilePath.java:2547)
	at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2918)
Also:   hudson.remoting.Channel$CallSiteStackTrace: Remote call to lucene2
		at hudson.remoting.Channel.attachCallSiteStackTrace(Channel.java:1741)
		at hudson.remoting.UserRequest$ExceptionResponse.retrieve(UserRequest.java:357)
		at hudson.remoting.Channel.call(Channel.java:955)
		at hudson.FilePath.act(FilePath.java:1036)
		at hudson.FilePath.act(FilePath.java:1025)
		at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
		at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
		at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
		at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
		at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
		at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
		at hudson.model.Build$BuildExecution.post2(Build.java:186)
		at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
		at hudson.model.Run.execute(Run.java:1819)
		at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
		at hudson.model.ResourceController.execute(ResourceController.java:97)
		at hudson.model.Executor.run(Executor.java:429)
Caused: hudson.FilePath$TunneledInterruptedException
	at hudson.FilePath$FileCallableWrapper.call(FilePath.java:2920)
	at hudson.remoting.UserRequest.perform(UserRequest.java:212)
	at hudson.remoting.UserRequest.perform(UserRequest.java:54)
	at hudson.remoting.Request$2.run(Request.java:369)
	at hudson.remoting.InterceptingExecutorService$1.call(InterceptingExecutorService.java:72)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:748)
Caused: java.lang.InterruptedException: java.lang.InterruptedException: no matches found within 10000
	at hudson.FilePath.act(FilePath.java:1038)
	at hudson.FilePath.act(FilePath.java:1025)
	at hudson.FilePath.validateAntFileMask(FilePath.java:2547)
	at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:243)
	at hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:81)
	at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
	at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:744)
	at hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:690)
	at hudson.model.Build$BuildExecution.post2(Build.java:186)
	at hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:635)
	at hudson.model.Run.execute(Run.java:1819)
	at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
	at hudson.model.ResourceController.execute(ResourceController.java:97)
	at hudson.model.Executor.run(Executor.java:429)
No artifacts found that match the file pattern "**/*.events,heapdumps/**,**/hs_err_pid*". Configuration error?
Recording test results
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)

Mime
View raw message