You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2020/08/02 02:25:45 UTC

[JENKINS] Lucene-Solr-Tests-master - Build # 4982 - Still Unstable

Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/4982/

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.search.facet.TestJsonFacets

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
	at __randomizedtesting.SeedInfo.seed([F5A152BCB3A7AF33]:0)


FAILED:  org.apache.solr.handler.TestContainerPlugin.testApiFromPackage

Error Message:
Error from server at http://127.0.0.1:37257/solr: Error executing command

Stack Trace:
org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteExecutionException: Error from server at http://127.0.0.1:37257/solr: Error executing command
	at __randomizedtesting.SeedInfo.seed([F5A152BCB3A7AF33:18FFAACD81ED78BA]:0)
	at org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteExecutionException.create(BaseHttpSolrClient.java:67)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:647)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
	at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
	at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:389)
	at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:359)
	at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1170)
	at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:931)
	at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:865)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:210)
	at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:227)
	at org.apache.solr.handler.TestContainerPlugin.testApiFromPackage(TestContainerPlugin.java:265)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
	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:370)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
	at java.base/java.lang.Thread.run(Thread.java:834)




Build Log:
[...truncated 12758 lines...]
   [junit4] Suite: org.apache.solr.handler.TestContainerPlugin
   [junit4]   2> 1324210 INFO  (SUITE-TestContainerPlugin-seed#[F5A152BCB3A7AF33]-worker) [     ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 1324210 INFO  (SUITE-TestContainerPlugin-seed#[F5A152BCB3A7AF33]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 1324210 INFO  (SUITE-TestContainerPlugin-seed#[F5A152BCB3A7AF33]-worker) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/data-dir-63-001
   [junit4]   2> 1324211 INFO  (SUITE-TestContainerPlugin-seed#[F5A152BCB3A7AF33]-worker) [     ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 1324212 INFO  (SUITE-TestContainerPlugin-seed#[F5A152BCB3A7AF33]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
   [junit4]   2> 1324215 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testApiFromPackage
   [junit4]   2> 1324215 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] 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/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001
   [junit4]   2> 1324216 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
   [junit4]   2> 1324216 INFO  (ZkTestServer Run Thread) [     ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
   [junit4]   2> 1324216 INFO  (ZkTestServer Run Thread) [     ] o.a.s.c.ZkTestServer Starting server
   [junit4]   2> 1324218 WARN  (ZkTestServer Run Thread) [     ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
   [junit4]   2> 1324316 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.ZkTestServer start zk server on port: 33068
   [junit4]   2> 1324316 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:33068
   [junit4]   2> 1324316 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:33068
   [junit4]   2> 1324316 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 33068
   [junit4]   2> 1324319 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324321 INFO  (zkConnectionManagerCallback-12078-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324321 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324324 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324325 INFO  (zkConnectionManagerCallback-12080-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324325 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324326 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324327 INFO  (zkConnectionManagerCallback-12082-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324328 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324432 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1324432 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1324432 INFO  (jetty-launcher-12083-thread-1) [     ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
   [junit4]   2> 1324433 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1324433 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1324433 INFO  (jetty-launcher-12083-thread-2) [     ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
   [junit4]   2> 1324434 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1324434 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1324434 INFO  (jetty-launcher-12083-thread-3) [     ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
   [junit4]   2> 1324434 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
   [junit4]   2> 1324434 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
   [junit4]   2> 1324434 INFO  (jetty-launcher-12083-thread-4) [     ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 11.0.4+10-LTS
   [junit4]   2> 1324436 INFO  (jetty-launcher-12083-thread-2) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1324436 INFO  (jetty-launcher-12083-thread-2) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1324436 INFO  (jetty-launcher-12083-thread-2) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-3) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-2) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@12c3e3de{/solr,null,AVAILABLE}
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-3) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-3) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-1) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-1) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-1) [     ] o.e.j.s.session node0 Scavenging every 600000ms
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-3) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@108ad885{/solr,null,AVAILABLE}
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-2) [     ] o.e.j.s.AbstractConnector Started ServerConnector@63f5747f{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39952}
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-2) [     ] o.e.j.s.Server Started @1324477ms
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-1) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@504dc3ae{/solr,null,AVAILABLE}
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39952}
   [junit4]   2> 1324437 INFO  (jetty-launcher-12083-thread-4) [     ] o.e.j.s.session DefaultSessionIdManager workerName=node0
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-4) [     ] o.e.j.s.session No SessionScavenger set, using defaults
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-3) [     ] o.e.j.s.AbstractConnector Started ServerConnector@6eca20b8{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:34470}
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-4) [     ] o.e.j.s.session node0 Scavenging every 660000ms
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-3) [     ] o.e.j.s.Server Started @1324478ms
   [junit4]   2> 1324438 ERROR (jetty-launcher-12083-thread-2) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34470}
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-1) [     ] o.e.j.s.AbstractConnector Started ServerConnector@797f0499{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:37257}
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-4) [     ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@310d2ec1{/solr,null,AVAILABLE}
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-1) [     ] o.e.j.s.Server Started @1324478ms
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2020-08-01T23:27:27.006455Z
   [junit4]   2> 1324438 ERROR (jetty-launcher-12083-thread-3) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37257}
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2020-08-01T23:27:27.006706Z
   [junit4]   2> 1324438 ERROR (jetty-launcher-12083-thread-1) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-4) [     ] o.e.j.s.AbstractConnector Started ServerConnector@3e729fd1{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:42548}
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-4) [     ] o.e.j.s.Server Started @1324479ms
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1324439 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
   [junit4]   2> 1324438 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42548}
   [junit4]   2> 1324439 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2020-08-01T23:27:27.007024Z
   [junit4]   2> 1324439 ERROR (jetty-launcher-12083-thread-4) [     ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
   [junit4]   2> 1324439 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
   [junit4]   2> 1324439 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.s.SolrDispatchFilter  ___      _       Welcome to Apache Solr? version 9.0.0
   [junit4]   2> 1324439 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324439 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _   Starting in cloud mode on port null
   [junit4]   2> 1324439 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_|  Install dir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr
   [junit4]   2> 1324439 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.s.SolrDispatchFilter |___/\___/_|_|    Start time: 2020-08-01T23:27:27.007429Z
   [junit4]   2> 1324439 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324440 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324440 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324440 INFO  (zkConnectionManagerCallback-12085-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324441 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324441 INFO  (zkConnectionManagerCallback-12087-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324453 INFO  (zkConnectionManagerCallback-12089-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324453 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324453 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324503 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1324503 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1324503 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1324503 INFO  (zkConnectionManagerCallback-12091-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324503 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324504 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
   [junit4]   2> 1324638 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1324639 WARN  (jetty-launcher-12083-thread-3) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6347c067[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324639 WARN  (jetty-launcher-12083-thread-3) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6347c067[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324642 WARN  (jetty-launcher-12083-thread-3) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3532ad6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324642 WARN  (jetty-launcher-12083-thread-3) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3532ad6[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324643 INFO  (jetty-launcher-12083-thread-3) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33068/solr
   [junit4]   2> 1324661 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324662 INFO  (zkConnectionManagerCallback-12101-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324662 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324750 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1324751 WARN  (jetty-launcher-12083-thread-4) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1c126b4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324751 WARN  (jetty-launcher-12083-thread-4) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1c126b4[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324775 WARN  (jetty-launcher-12083-thread-4) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5052027c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324775 WARN  (jetty-launcher-12083-thread-4) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5052027c[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324776 INFO  (jetty-launcher-12083-thread-4) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33068/solr
   [junit4]   2> 1324777 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324790 INFO  (zkConnectionManagerCallback-12113-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324790 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324831 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42548_solr
   [junit4]   2> 1324832 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.c.Overseer Overseer (id=73243794461949960-127.0.0.1:42548_solr-n_0000000000) starting
   [junit4]   2> 1324838 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1324839 WARN  (jetty-launcher-12083-thread-1) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@43f99b4f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324839 WARN  (jetty-launcher-12083-thread-1) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@43f99b4f[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324858 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42548_solr
   [junit4]   2> 1324858 INFO  (OverseerStateUpdate-73243794461949960-127.0.0.1:42548_solr-n_0000000000) [n:127.0.0.1:42548_solr     ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42548_solr
   [junit4]   2> 1324859 WARN  (jetty-launcher-12083-thread-1) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@153bbe8[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324859 WARN  (jetty-launcher-12083-thread-1) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@153bbe8[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324875 INFO  (jetty-launcher-12083-thread-1) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33068/solr
   [junit4]   2> 1324877 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324880 INFO  (OverseerStateUpdate-73243794461949960-127.0.0.1:42548_solr-n_0000000000) [n:127.0.0.1:42548_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1324880 INFO  (zkConnectionManagerCallback-12127-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324880 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1324881 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 1324881 WARN  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
   [junit4]   2> 1324899 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
   [junit4]   2> 1324912 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.c.ZkController Publish node=127.0.0.1:37257_solr as DOWN
   [junit4]   2> 1324913 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
   [junit4]   2> 1324913 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37257_solr
   [junit4]   2> 1324914 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1324915 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
   [junit4]   2> 1324925 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 1324925 WARN  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
   [junit4]   2> 1324938 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 1324957 WARN  (jetty-launcher-12083-thread-2) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@581f500[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324957 WARN  (jetty-launcher-12083-thread-2) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@581f500[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324960 WARN  (jetty-launcher-12083-thread-2) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@79acb351[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324960 WARN  (jetty-launcher-12083-thread-2) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@79acb351[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 1324978 INFO  (jetty-launcher-12083-thread-2) [     ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:33068/solr
   [junit4]   2> 1324979 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1324998 INFO  (zkConnectionManagerCallback-12142-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1324998 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1325011 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1325015 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
   [junit4]   2> 1325030 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1325040 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1325042 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.c.ZkController Publish node=127.0.0.1:34470_solr as DOWN
   [junit4]   2> 1325043 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
   [junit4]   2> 1325044 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34470_solr
   [junit4]   2> 1325045 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1325045 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1325046 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.c.ZkController Publish node=127.0.0.1:39952_solr as DOWN
   [junit4]   2> 1325046 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1325046 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
   [junit4]   2> 1325047 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 1325047 WARN  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
   [junit4]   2> 1325047 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
   [junit4]   2> 1325047 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39952_solr
   [junit4]   2> 1325049 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1325049 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1325049 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1325049 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
   [junit4]   2> 1325063 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.p.PackageLoader /packages.json updated to version -1
   [junit4]   2> 1325064 WARN  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
   [junit4]   2> 1325174 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1325188 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
   [junit4]   2> 1325207 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42548.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325211 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37257.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325268 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42548.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325268 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42548.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325282 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37257.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325282 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37257.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325282 INFO  (jetty-launcher-12083-thread-4) [n:127.0.0.1:42548_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/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node4
   [junit4]   2> 1325284 INFO  (jetty-launcher-12083-thread-1) [n:127.0.0.1:37257_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/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node1
   [junit4]   2> 1325301 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34470.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325305 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39952.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325362 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39952.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325374 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39952.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325375 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34470.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325375 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_solr     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34470.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@30c4ef96
   [junit4]   2> 1325378 INFO  (jetty-launcher-12083-thread-2) [n:127.0.0.1:39952_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/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node2
   [junit4]   2> 1325378 INFO  (jetty-launcher-12083-thread-3) [n:127.0.0.1:34470_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/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node3
   [junit4]   2> 1325419 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
   [junit4]   2> 1325420 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
   [junit4]   2> 1325421 INFO  (zkConnectionManagerCallback-12170-thread-1) [     ] o.a.s.c.c.ConnectionManager zkClient has connected
   [junit4]   2> 1325421 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
   [junit4]   2> 1325423 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
   [junit4]   2> 1325424 INFO  (TEST-TestContainerPlugin.testApiFromPackage-seed#[F5A152BCB3A7AF33]) [     ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:33068/solr ready
   [junit4]   2> 1325439 INFO  (qtp1611518665-19569) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:42548_solr} status=0 QTime=0
   [junit4]   2> 1325439 INFO  (updateExecutor-12097-thread-1-processing-n:127.0.0.1:34470_solr) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:42548_solr 
   [junit4]   2> 1325441 INFO  (qtp2008609822-19568) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:42548_solr} status=0 QTime=0
   [junit4]   2> 1325441 INFO  (qtp817194935-19571) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
   [junit4]   2> 1325441 INFO  (updateExecutor-12121-thread-1-processing-n:127.0.0.1:37257_solr) [n:127.0.0.1:37257_solr     ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:42548_solr 
   [junit4]   2> 1325442 INFO  (qtp817194935-19578) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
   [junit4]   2> 1325442 INFO  (qtp817194935-19571) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
   [junit4]   2> 1325442 INFO  (qtp1832028073-19559) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={getFrom=127.0.0.1:42548_solr} status=0 QTime=0
   [junit4]   2> 1325442 INFO  (updateExecutor-12137-thread-1-processing-n:127.0.0.1:39952_solr) [n:127.0.0.1:39952_solr     ] o.a.s.f.DistribPackageStore fetching a file /_trusted_/keys/pub_key512.der from 127.0.0.1:42548_solr 
   [junit4]   2> 1325443 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={sync=true} status=0 QTime=9
   [junit4]   1> sync resp: http://127.0.0.1:42548/api/node/files/_trusted_/keys/pub_key512.der?sync=true ,is: null
   [junit4]   2> 1325443 INFO  (qtp817194935-19571) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
   [junit4]   2> 1325443 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/.pub_key512.der.json params={} status=0 QTime=0
   [junit4]   2> 1325445 INFO  (qtp817194935-19578) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={} status=0 QTime=0
   [junit4]   2> 1325455 INFO  (updateExecutor-12097-thread-1-processing-n:127.0.0.1:34470_solr) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
   [junit4]   2> 1325456 INFO  (updateExecutor-12097-thread-1-processing-n:127.0.0.1:34470_solr) [n:127.0.0.1:34470_solr     ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
   [junit4]   2> 1325461 INFO  (qtp2008609822-19562) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325462 INFO  (updateExecutor-12137-thread-1-processing-n:127.0.0.1:39952_solr) [n:127.0.0.1:39952_solr     ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
   [junit4]   2> 1325462 INFO  (updateExecutor-12137-thread-1-processing-n:127.0.0.1:39952_solr) [n:127.0.0.1:39952_solr     ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
   [junit4]   2> 1325466 INFO  (updateExecutor-12121-thread-1-processing-n:127.0.0.1:37257_solr) [n:127.0.0.1:37257_solr     ] o.a.s.f.DistribPackageStore persisted a file /_trusted_/keys/pub_key512.der and metadata. sizes 94 141
   [junit4]   2> 1325466 INFO  (updateExecutor-12121-thread-1-processing-n:127.0.0.1:37257_solr) [n:127.0.0.1:37257_solr     ] o.a.s.f.PackageStoreAPI downloaded file: /_trusted_/keys/pub_key512.der
   [junit4]   2> 1325565 INFO  (qtp2008609822-19568) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325568 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325572 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325576 INFO  (qtp1832028073-19554) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/_trusted_/keys/pub_key512.der params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325582 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325584 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.f.DistribPackageStore Missing file in package store: /myplugin/v1.jar
   [junit4]   2> 1325584 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325585 INFO  (qtp1832028073-19554) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325586 INFO  (qtp2008609822-19568) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325586 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.f.DistribPackageStore Unable to download file : /myplugin/v1.jar
   [junit4]   2> 1325593 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.f.DistribPackageStore persisted a file /myplugin/v1.jar and metadata. sizes 867 246
   [junit4]   2> 1325596 INFO  (qtp1832028073-19559) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={getFrom=127.0.0.1:42548_solr} status=0 QTime=0
   [junit4]   2> 1325596 INFO  (updateExecutor-12137-thread-1-processing-n:127.0.0.1:39952_solr) [n:127.0.0.1:39952_solr     ] o.a.s.f.DistribPackageStore fetching a file /myplugin/v1.jar from 127.0.0.1:42548_solr 
   [junit4]   2> 1325597 INFO  (qtp2008609822-19574) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={getFrom=127.0.0.1:42548_solr} status=0 QTime=0
   [junit4]   2> 1325597 INFO  (updateExecutor-12121-thread-1-processing-n:127.0.0.1:37257_solr) [n:127.0.0.1:37257_solr     ] o.a.s.f.DistribPackageStore fetching a file /myplugin/v1.jar from 127.0.0.1:42548_solr 
   [junit4]   2> 1325597 INFO  (qtp817194935-19578) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/.v1.jar.json params={} status=0 QTime=0
   [junit4]   2> 1325597 INFO  (qtp1611518665-19569) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={getFrom=127.0.0.1:42548_solr} status=0 QTime=0
   [junit4]   2> 1325598 INFO  (qtp817194935-19571) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/.v1.jar.json params={} status=0 QTime=0
   [junit4]   2> 1325598 INFO  (qtp817194935-19655) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={} status=0 QTime=0
   [junit4]   2> 1325599 INFO  (qtp817194935-19571) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={} status=0 QTime=0
   [junit4]   2> 1325600 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/myplugin/v1.jar params={sig=pmrmWCDafdNpYle2rueAGnU2J6NYlcAey9mkZYbqh%2B5RdYo2Ln%2BllLF9voyRj%2BDDivK9GV1XdtKvD9rgCxlD7Q%3D%3D&wt=javabin&version=2} status=0 QTime=20
   [junit4]   2> 1325610 INFO  (updateExecutor-12097-thread-1-processing-n:127.0.0.1:34470_solr) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore fetching a file /myplugin/v1.jar from 127.0.0.1:42548_solr 
   [junit4]   2> 1325617 INFO  (updateExecutor-12121-thread-1-processing-n:127.0.0.1:37257_solr) [n:127.0.0.1:37257_solr     ] o.a.s.f.DistribPackageStore persisted a file /myplugin/v1.jar and metadata. sizes 867 246
   [junit4]   2> 1325617 INFO  (updateExecutor-12137-thread-1-processing-n:127.0.0.1:39952_solr) [n:127.0.0.1:39952_solr     ] o.a.s.f.DistribPackageStore persisted a file /myplugin/v1.jar and metadata. sizes 867 246
   [junit4]   2> 1325617 INFO  (updateExecutor-12121-thread-1-processing-n:127.0.0.1:37257_solr) [n:127.0.0.1:37257_solr     ] o.a.s.f.PackageStoreAPI downloaded file: /myplugin/v1.jar
   [junit4]   2> 1325617 INFO  (updateExecutor-12137-thread-1-processing-n:127.0.0.1:39952_solr) [n:127.0.0.1:39952_solr     ] o.a.s.f.PackageStoreAPI downloaded file: /myplugin/v1.jar
   [junit4]   2> 1325619 INFO  (qtp2008609822-19568) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325623 INFO  (qtp817194935-19578) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/.v1.jar.json params={} status=0 QTime=0
   [junit4]   2> 1325624 INFO  (qtp817194935-19655) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={} status=0 QTime=0
   [junit4]   2> 1325630 INFO  (updateExecutor-12097-thread-1-processing-n:127.0.0.1:34470_solr) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore persisted a file /myplugin/v1.jar and metadata. sizes 867 246
   [junit4]   2> 1325630 INFO  (updateExecutor-12097-thread-1-processing-n:127.0.0.1:34470_solr) [n:127.0.0.1:34470_solr     ] o.a.s.f.PackageStoreAPI downloaded file: /myplugin/v1.jar
   [junit4]   2> 1325649 INFO  (qtp817194935-19571) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325653 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325656 INFO  (qtp1832028073-19554) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v1.jar params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325661 INFO  (qtp1611518665-19569) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325663 INFO  (qtp1611518665-19569) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore Missing file in package store: /myplugin/v2.jar
   [junit4]   2> 1325664 INFO  (qtp2008609822-19574) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325665 INFO  (qtp1832028073-19559) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325666 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={omitHeader=true&meta=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325666 INFO  (qtp1611518665-19569) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore Unable to download file : /myplugin/v2.jar
   [junit4]   2> 1325680 INFO  (qtp1611518665-19569) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore persisted a file /myplugin/v2.jar and metadata. sizes 867 246
   [junit4]   2> 1325685 INFO  (qtp1832028073-19565) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={getFrom=127.0.0.1:34470_solr} status=0 QTime=0
   [junit4]   2> 1325685 INFO  (updateExecutor-12137-thread-1-processing-n:127.0.0.1:39952_solr) [n:127.0.0.1:39952_solr     ] o.a.s.f.DistribPackageStore fetching a file /myplugin/v2.jar from 127.0.0.1:34470_solr 
   [junit4]   2> 1325685 INFO  (qtp817194935-19571) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={getFrom=127.0.0.1:34470_solr} status=0 QTime=0
   [junit4]   2> 1325686 INFO  (updateExecutor-12109-thread-3-processing-n:127.0.0.1:42548_solr) [n:127.0.0.1:42548_solr     ] o.a.s.f.DistribPackageStore fetching a file /myplugin/v2.jar from 127.0.0.1:34470_solr 
   [junit4]   2> 1325686 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/.v2.jar.json params={} status=0 QTime=0
   [junit4]   2> 1325686 INFO  (qtp2008609822-19562) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={getFrom=127.0.0.1:34470_solr} status=0 QTime=0
   [junit4]   2> 1325686 INFO  (updateExecutor-12121-thread-1-processing-n:127.0.0.1:37257_solr) [n:127.0.0.1:37257_solr     ] o.a.s.f.DistribPackageStore fetching a file /myplugin/v2.jar from 127.0.0.1:34470_solr 
   [junit4]   2> 1325687 INFO  (qtp1611518665-19573) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/.v2.jar.json params={} status=0 QTime=0
   [junit4]   2> 1325687 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={} status=0 QTime=0
   [junit4]   2> 1325688 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/.v2.jar.json params={} status=0 QTime=0
   [junit4]   2> 1325689 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={} status=0 QTime=0
   [junit4]   2> 1325695 INFO  (updateExecutor-12121-thread-1-processing-n:127.0.0.1:37257_solr) [n:127.0.0.1:37257_solr     ] o.a.s.f.DistribPackageStore persisted a file /myplugin/v2.jar and metadata. sizes 867 246
   [junit4]   2> 1325695 INFO  (updateExecutor-12121-thread-1-processing-n:127.0.0.1:37257_solr) [n:127.0.0.1:37257_solr     ] o.a.s.f.PackageStoreAPI downloaded file: /myplugin/v2.jar
   [junit4]   2> 1325700 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={} status=0 QTime=0
   [junit4]   2> 1325703 INFO  (updateExecutor-12137-thread-1-processing-n:127.0.0.1:39952_solr) [n:127.0.0.1:39952_solr     ] o.a.s.f.DistribPackageStore persisted a file /myplugin/v2.jar and metadata. sizes 867 246
   [junit4]   2> 1325703 INFO  (updateExecutor-12137-thread-1-processing-n:127.0.0.1:39952_solr) [n:127.0.0.1:39952_solr     ] o.a.s.f.PackageStoreAPI downloaded file: /myplugin/v2.jar
   [junit4]   2> 1325703 INFO  (qtp1611518665-19569) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/files/myplugin/v2.jar params={sig=StR3DmqaUSL7qjDOeVEiCqE%2BouiZAkW99fsL48F9oWG047o7NGgwwZ36iGgzDC3S2tPaFjRAd9Zg4UK7OZLQzg%3D%3D&wt=javabin&version=2} status=0 QTime=44
   [junit4]   2> 1325706 INFO  (updateExecutor-12109-thread-3-processing-n:127.0.0.1:42548_solr) [n:127.0.0.1:42548_solr     ] o.a.s.f.DistribPackageStore persisted a file /myplugin/v2.jar and metadata. sizes 867 246
   [junit4]   2> 1325706 INFO  (updateExecutor-12109-thread-3-processing-n:127.0.0.1:42548_solr) [n:127.0.0.1:42548_solr     ] o.a.s.f.PackageStoreAPI downloaded file: /myplugin/v2.jar
   [junit4]   2> 1325727 INFO  (qtp2008609822-19574) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325742 INFO  (qtp817194935-19655) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325746 INFO  (qtp1611518665-19573) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325750 INFO  (qtp1832028073-19559) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/node/files/myplugin/v2.jar params={meta=true&wt=javabin} status=0 QTime=1
   [junit4]   2> 1325755 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325765 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325769 INFO  (qtp817194935-19578) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325769 INFO  (qtp2008609822-19574) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325770 INFO  (qtp1832028073-19559) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1325770 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.p.PackageLoader /packages.json updated to version 1
   [junit4]   2> 1325770 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.p.PackageLoader A new package: mypkg introduced
   [junit4]   2> 1325770 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/myplugin/v1.jar]
   [junit4]   2> 1325782 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325785 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.p.PackageLoader /packages.json updated to version 0
   [junit4]   2> 1325785 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.p.PackageLoader A new package: mypkg introduced
   [junit4]   2> 1325785 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/myplugin/v1.jar]
   [junit4]   2> 1325785 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.p.PackageLoader /packages.json updated to version 0
   [junit4]   2> 1325785 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.p.PackageLoader A new package: mypkg introduced
   [junit4]   2> 1325785 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/myplugin/v1.jar]
   [junit4]   2> 1325785 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.p.PackageLoader /packages.json updated to version 0
   [junit4]   2> 1325785 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.p.PackageLoader A new package: mypkg introduced
   [junit4]   2> 1325806 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.p.PackageLoader /packages.json updated to version 0
   [junit4]   2> 1325806 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.p.PackageLoader A new package: mypkg introduced
   [junit4]   2> 1325806 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.p.PackageLoader A new version: 1.0 added for package: mypkg with artifacts [/myplugin/v1.jar]
   [junit4]   2> 1325807 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325807 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325807 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325808 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325808 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325808 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325808 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node3/filestore/myplugin]
   [junit4]   2> 1325808 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
   [junit4]   2> 1325809 INFO  (qtp1611518665-19667) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin&version=2} status=0 QTime=57
   [junit4]   2> 1325810 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node4/filestore/myplugin]
   [junit4]   2> 1325810 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
   [junit4]   2> 1325822 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325822 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node2/filestore/myplugin]
   [junit4]   2> 1325822 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
   [junit4]   2> 1325823 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node1/filestore/myplugin]
   [junit4]   2> 1325823 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.p.PackageLoader version: 1.0 is the new latest in package: mypkg
   [junit4]   2> 1325833 INFO  (qtp2008609822-19568) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin} status=0 QTime=0
   [junit4]   2> 1325836 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin} status=0 QTime=0
   [junit4]   2> 1325846 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin} status=0 QTime=0
   [junit4]   2> 1325848 INFO  (qtp1832028073-19554) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin} status=0 QTime=0
   [junit4]   2> 1325856 INFO  (qtp2008609822-19574) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/plugin params={wt=javabin&version=2} status=0 QTime=6
   [junit4]   2> 1325857 INFO  (qtp1611518665-19573) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/plugin params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1325858 ERROR (qtp2008609822-19562) [n:127.0.0.1:37257_solr     ] o.a.s.a.V2HttpCall >> path: '/plugin/my/path'
   [junit4]   2> 1325859 ERROR (qtp2008609822-19562) [n:127.0.0.1:37257_solr     ] o.a.s.a.V2HttpCall Error in init()
   [junit4]   2>           => org.apache.solr.common.SolrException: no core retrieved for null
   [junit4]   2> 	at org.apache.solr.api.V2HttpCall.init(V2HttpCall.java:139)
   [junit4]   2> org.apache.solr.common.SolrException: no core retrieved for null
   [junit4]   2> 	at org.apache.solr.api.V2HttpCall.init(V2HttpCall.java:139) [java/:?]
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:546) [java/:?]
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415) [java/:?]
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345) [java/:?]
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166) [java/:?]
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545) [jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1610) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1300) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485) [jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1580) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1215) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322) [jetty-rewrite-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:767) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:500) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [jetty-io-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [jetty-io-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [jetty-io-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806) [jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938) [jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:834) [?:?]
   [junit4]   2> 1325980 INFO  (qtp817194935-19655) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/plugin/my/path params={wt=javabin&version=2} status=0 QTime=0
   [junit4]   2> 1325996 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1325998 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1326051 INFO  (qtp2008609822-19568) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1326051 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.p.PackageLoader /packages.json updated to version 1
   [junit4]   2> 1326052 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.p.PackageLoader Package mypkg is modified 
   [junit4]   2> 1326051 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.p.PackageLoader /packages.json updated to version 1
   [junit4]   2> 1326051 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.p.PackageLoader /packages.json updated to version 1
   [junit4]   2> 1326052 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.p.PackageLoader A new version: 2.0 added for package: mypkg with artifacts [/myplugin/v2.jar]
   [junit4]   2> 1326052 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.p.PackageLoader Package mypkg is modified 
   [junit4]   2> 1326052 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.p.PackageLoader Package mypkg is modified 
   [junit4]   2> 1326052 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.p.PackageLoader A new version: 2.0 added for package: mypkg with artifacts [/myplugin/v2.jar]
   [junit4]   2> 1326052 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.p.PackageLoader A new version: 2.0 added for package: mypkg with artifacts [/myplugin/v2.jar]
   [junit4]   2> 1326052 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.p.PackageLoader /packages.json updated to version 1
   [junit4]   2> 1326052 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.p.PackageLoader Package mypkg is modified 
   [junit4]   2> 1326052 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.p.PackageLoader A new version: 2.0 added for package: mypkg with artifacts [/myplugin/v2.jar]
   [junit4]   2> 1326052 INFO  (qtp817194935-19575) [n:127.0.0.1:42548_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1326053 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1326054 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1326055 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1326055 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1326056 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node2/filestore/myplugin]
   [junit4]   2> 1326061 INFO  (qtp1832028073-19554) [n:127.0.0.1:39952_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={expectedVersion1=&omitHeader=true&wt=javabin} status=0 QTime=0
   [junit4]   2> 1326061 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.p.PackageLoader /packages.json updated to version 1
   [junit4]   2> 1326061 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.p.PackageLoader Package mypkg is modified 
   [junit4]   2> 1326070 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1326070 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1326071 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1326071 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.f.DistribPackageStore pub_key512.der does not exist locally, downloading.. 
   [junit4]   2> 1326073 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node3/filestore/myplugin]
   [junit4]   2> 1326056 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node4/filestore/myplugin]
   [junit4]   2> 1326081 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.p.PackageLoader version: 2.0 is the new latest in package: mypkg
   [junit4]   2> 1326081 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.p.PackageLoader version: 2.0 is the new latest in package: mypkg
   [junit4]   2> 1326085 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.p.PackageLoader version: 2.0 is the new latest in package: mypkg
   [junit4]   2> 1326085 INFO  (qtp1611518665-19567) [n:127.0.0.1:34470_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/package params={wt=javabin&version=2} status=0 QTime=103
   [junit4]   2> 1326088 ERROR (qtp2008609822-19574) [n:127.0.0.1:37257_solr     ] o.a.s.a.AnnotatedApi Error executing command  
   [junit4]   2>           => org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error executing command, errors: [{update=org.apache.solr.client.solrj.request.beans.PluginMeta@5f226d1d, errorMessages=[No such package version:mypkg:2.0 . available versions :[1.0]]}], 
   [junit4]   2> 	at org.apache.solr.api.AnnotatedApi$Cmd.checkForErrorInPayload(AnnotatedApi.java:323)
   [junit4]   2> org.apache.solr.api.ApiBag$ExceptionWithErrObject: Error executing command
   [junit4]   2> 	at org.apache.solr.api.AnnotatedApi$Cmd.checkForErrorInPayload(AnnotatedApi.java:323) ~[java/:?]
   [junit4]   2> 	at org.apache.solr.api.AnnotatedApi$Cmd.invoke(AnnotatedApi.java:293) [java/:?]
   [junit4]   2> 	at org.apache.solr.api.AnnotatedApi.call(AnnotatedApi.java:196) [java/:?]
   [junit4]   2> 	at org.apache.solr.api.V2HttpCall.handleAdmin(V2HttpCall.java:340) [java/:?]
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:818) [java/:?]
   [junit4]   2> 	at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:566) [java/:?]
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:415) [java/:?]
   [junit4]   2> 	at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:345) [java/:?]
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166) [java/:?]
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1604) [jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:545) [jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1610) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1300) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:485) [jetty-servlet-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1580) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1215) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322) [jetty-rewrite-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:717) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.Server.handle(Server.java:500) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:383) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:547) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:375) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:273) [jetty-server-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [jetty-io-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:103) [jetty-io-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:117) [jetty-io-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806) [jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938) [jetty-util-9.4.27.v20200227.jar:9.4.27.v20200227]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:834) [?:?]
   [junit4]   2> 1326089 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.c.SolrResourceLoader Added 1 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.handler.TestContainerPlugin_F5A152BCB3A7AF33-001/tempDir-001/node1/filestore/myplugin]
   [junit4]   2> 1326097 INFO  (qtp2008609822-19574) [n:127.0.0.1:37257_solr     ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/plugin params={wt=javabin&version=2} status=400 QTime=11
   [junit4]   2> 1326097 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.p.PackageLoader version: 2.0 is the new latest in package: mypkg
   [junit4]   2> 1326203 INFO  (jetty-closer-12171-thread-1) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=840471587
   [junit4]   2> 1326204 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=447239711
   [junit4]   2> 1326204 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:42548_solr
   [junit4]   2> 1326204 INFO  (jetty-closer-12171-thread-1) [     ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:37257_solr
   [junit4]   2> 1326204 INFO  (jetty-closer-12171-thread-3) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1267089554
   [junit4]   2> 1326204 INFO  (jetty-closer-12171-thread-3) [     ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:34470_solr
   [junit4]   2> 1326205 INFO  (jetty-closer-12171-thread-1) [     ] o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 1326205 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 1326205 INFO  (jetty-closer-12171-thread-1) [     ] o.a.s.c.ZkController Publish node=127.0.0.1:37257_solr as DOWN
   [junit4]   2> 1326205 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.c.ZkController Publish node=127.0.0.1:42548_solr as DOWN
   [junit4]   2> 1326205 INFO  (jetty-closer-12171-thread-3) [     ] o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 1326205 INFO  (jetty-closer-12171-thread-3) [     ] o.a.s.c.ZkController Publish node=127.0.0.1:34470_solr as DOWN
   [junit4]   2> 1326205 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
   [junit4]   2> 1326205 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
   [junit4]   2> 1326205 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
   [junit4]   2> 1326206 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (1)
   [junit4]   2> 1326207 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
   [junit4]   2> 1326208 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
   [junit4]   2> 1326208 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4f22427e: rootName = solr_42548, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@7ab0dfae
   [junit4]   2> 1326209 INFO  (jetty-closer-12171-thread-1) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
   [junit4]   2> 1326209 INFO  (jetty-closer-12171-thread-1) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b04ead0: rootName = solr_37257, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@48c4300c
   [junit4]   2> 1326209 INFO  (jetty-closer-12171-thread-3) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
   [junit4]   2> 1326209 INFO  (jetty-closer-12171-thread-3) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@44a544f2: rootName = solr_34470, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@3dae0213
   [junit4]   2> 1326239 INFO  (jetty-closer-12171-thread-4) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1655082360
   [junit4]   2> 1326239 INFO  (jetty-closer-12171-thread-4) [     ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:39952_solr
   [junit4]   2> 1326240 INFO  (jetty-closer-12171-thread-4) [     ] o.a.s.c.ZkController Publish this node as DOWN...
   [junit4]   2> 1326240 INFO  (jetty-closer-12171-thread-4) [     ] o.a.s.c.ZkController Publish node=127.0.0.1:39952_solr as DOWN
   [junit4]   2> 1326240 INFO  (zkCallback-12100-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 1326240 INFO  (zkCallback-12126-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 1326240 INFO  (zkCallback-12141-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 1326241 INFO  (zkCallback-12112-thread-1) [     ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
   [junit4]   2> 1326259 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
   [junit4]   2> 1326259 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7133cedf: rootName = solr_42548, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@317e1194
   [junit4]   2> 1326259 INFO  (jetty-closer-12171-thread-4) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
   [junit4]   2> 1326260 INFO  (jetty-closer-12171-thread-4) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@753fcd4f: rootName = solr_39952, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@35b7e018
   [junit4]   2> 1326323 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
   [junit4]   2> 1326323 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@516e6295: rootName = solr_42548, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@282abb15
   [junit4]   2> 1326323 INFO  (jetty-closer-12171-thread-2) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
   [junit4]   2> 1326324 INFO  (jetty-closer-12171-thread-1) [     ] o.a.s.m.So

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

WhatYouKill.java:184)
   [junit4]   2>         at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:171)
   [junit4]   2>         at app//org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.produce(EatWhatYouKill.java:135)
   [junit4]   2>         at app//org.eclipse.jetty.io.ManagedSelector$$Lambda$216/0x0000000100382040.run(Unknown Source)
   [junit4]   2>         at app//org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:806)
   [junit4]   2>         at app//org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:938)
   [junit4]   2>         at java.base@11.0.4/java.lang.Thread.run(Thread.java:834)
   [junit4]   2>   56) Thread[id=42384, name=MetricsHistoryHandler-24503-thread-1, state=TIMED_WAITING, group=TGRP-TestJsonFacets]
   [junit4]   2>         at java.base@11.0.4/jdk.internal.misc.Unsafe.park(Native Method)
   [junit4]   2>         at java.base@11.0.4/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
   [junit4]   2>         at java.base@11.0.4/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123)
   [junit4]   2>         at java.base@11.0.4/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1182)
   [junit4]   2>         at java.base@11.0.4/java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:899)
   [junit4]   2>         at java.base@11.0.4/java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1054)
   [junit4]   2>         at java.base@11.0.4/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1114)
   [junit4]   2>         at java.base@11.0.4/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
   [junit4]   2>         at java.base@11.0.4/java.lang.Thread.run(Thread.java:834)
   [junit4]   2> NOTE: test params are: codec=HighCompressionCompressingStoredFieldsData(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=17726, maxDocsPerChunk=4, blockShift=12), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=HIGH_COMPRESSION, chunkSize=17726, blockSize=12)), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=mk, timezone=GMT
   [junit4]   2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=8,threads=66,free=97964056,total=536870912
   [junit4]   2> NOTE: All tests run in this JVM: [TestPrepRecovery, SpellCheckCollatorTest, HdfsDirectoryTest, CircularListTest, TestConfigSets, CloneFieldUpdateProcessorFactoryTest, TestConfigReload, UpdateRequestProcessorFactoryTest, XsltUpdateRequestHandlerTest, BasicAuthIntegrationTest, TestDocumentBuilder, SmileWriterTest, TestTlogReplica, TestPartialUpdateDeduplication, TestNRTOpen, FullSolrCloudDistribCmdsTest, CrossCollectionJoinQueryTest, TestDocTermOrdsUninvertLimit, ChangedSchemaMergeTest, AnalyticsMergeStrategyTest, TestSimpleTrackingShardHandler, V2ApiIntegrationTest, RequiredFieldsTest, SignatureUpdateProcessorFactoryTest, TestDynamicFieldCollectionResource, TestFunctionQuery, DistributedQueryComponentCustomSortTest, TestEmbeddedSolrServerConstructors, BigEndianAscendingWordSerializerTest, ExternalFileFieldSortTest, BlockCacheTest, DirectSolrConnectionTest, DocValuesNotIndexedTest, BJQFilterAccessibleTest, TestStressLucene, SoftAutoCommitTest, SuggesterFSTTest, TestFastOutputStream, TestUseDocValuesAsStored, TestInPlaceUpdatesDistrib, TestManagedStopFilterFactory, MoveReplicaHDFSFailoverTest, TestHdfsCloudBackupRestore, TestClusterStateMutator, URLClassifyProcessorTest, SolrTestCaseJ4Test, UnloadDistributedZkTest, CoreAdminHandlerTest, TestRealTimeGet, SpellingQueryConverterTest, ZkControllerTest, TemplateUpdateProcessorTest, SimplePostToolTest, SuggestComponentTest, TestMultiWordSynonyms, TestLeaderElectionWithEmptyReplica, TestMacroExpander, CollectionStateZnodeTest, TestTolerantSearch, TestUnifiedSolrHighlighterWithoutStoredId, IndexSizeEstimatorTest, TestSchemaNameResource, TestSolrJacksonAnnotation, AtomicUpdateRemovalJavabinTest, TaggingAttributeTest, SliceStateTest, DistributedUpdateProcessorTest, HdfsSyncSliceTest, TestCloudJSONFacetSKG, NumberUtilsTest, RangeFacetCloudTest, TestDynamicFieldNamesIndexCorrectly, TestNestedDocsSort, TestRawResponseWriter, ReindexCollectionTest, DistributedFacetPivotSmallTest, SpellCheckComponentTest, RequestHandlersTest, HdfsRecoverLeaseTest, HttpPartitionWithTlogReplicasTest, SolrJmxReporterCloudTest, WrapperMergePolicyFactoryTest, TestSolrQueryResponse, TestLRUStatsCache, LeaderTragicEventTest, PluginInfoTest, TestSolrIndexConfig, PeerSyncReplicationTest, TestLMDirichletSimilarityFactory, CollectionReloadTest, TestDelegationWithHadoopAuth, TestMaxScoreQueryParser, TestReloadAndDeleteDocs, RulesTest, TestExternalRoleRuleBasedAuthorizationPlugin, SimpleMLTQParserTest, DirectoryFactoryTest, BlobRepositoryCloudTest, VersionInfoTest, TestFuzzyAnalyzedSuggestions, DistribJoinFromCollectionTest, HighlighterWithoutStoredIdTest, TokenizerChainTest, SolrIndexMetricsTest, TermsComponentTest, CustomHighlightComponentTest, TestCloudPhrasesIdentificationComponent, OverseerSolrResponseTest, PKIAuthenticationIntegrationTest, SplitByPrefixTest, TestClusterProperties, DateFieldTest, BasicDistributedZkTest, TestStressUserVersions, TestFieldResource, SolrXmlInZkTest, TestJsonFacetsWithNestedObjects, HdfsChaosMonkeyNothingIsSafeTest, SynonymTokenizerTest, NestedAtomicUpdateTest, TestQueryingOnDownCollection, TestApiFramework, TestRandomRequestDistribution, TestLockTree, DirectUpdateHandlerTest, ConvertedLegacyTest, RegexBoostProcessorTest, MetricsConfigTest, ZookeeperStatusHandlerTest, TestJsonFacetRefinement, SolrCoreCheckLockOnStartupTest, TestPayloadCheckQParserPlugin, TestDynamicLoading, TestSolrCloudWithKerberosAlt, TestJavabinTupleStreamParser, PreAnalyzedFieldTest, TestSolrCloudWithHadoopAuthPlugin, TestRTimerTree, TestCollationFieldDocValues, TestBadConfig, ClassificationUpdateProcessorFactoryTest, TestLegacyFieldReuse, TestRequestStatusCollectionAPI, CleanupOldIndexTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, DeleteInactiveReplicaTest, DeleteShardTest, DistribCursorPagingTest, LeaderVoteWaitTimeoutTest, MultiSolrCloudTestCaseTest, NestedShardedAtomicUpdateTest, NodeMutatorTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerTaskQueueTest, RemoteQueryErrorTest, ReplaceNodeTest, RestartWhileUpdatingTest, RollingRestartTest, RouteFieldTest, SaslZkACLProviderTest, ShardRoutingCustomTest, ShardRoutingTest, SystemCollectionCompatTest, TestCloudConsistency, TestCloudPseudoReturnFields, TestCloudRecovery, TestCryptoKeys, TestPullReplica, TestRebalanceLeaders, TestStressCloudBlindAtomicUpdates, TestWaitForStateWithJettyShutdowns, ZkNodePropsTest, ZkShardTermsTest, CollectionsAPIAsyncDistributedZkTest, HdfsCollectionsAPIDistributedZkTest, TestCollectionAPI, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CdcrWithNodesRestartsTest, HDFSCollectionsAPITest, HdfsBasicDistributedZk2Test, HdfsChaosMonkeySafeLeaderTest, HdfsTlogReplayBufferedWhileIndexingTest, HdfsUnloadDistributedZkTest, ZkCollectionPropsCachingTest, ByteBuffersDirectoryFactoryTest, ConfigureRecoveryStrategyTest, ResourceLoaderTest, TestConfig, TestConfigOverlay, TestCoreContainer, TestDynamicURP, TestImplicitCoreProperties, TestInfoStreamLogging, TestJmxIntegration, TestShardHandlerFactory, TestXIncludeConfig, TestSolrCloudSnapshots, TestSolrCoreSnapshots, FieldAnalysisRequestHandlerTest, JsonLoaderTest, PingRequestHandlerTest, TestHdfsBackupRestoreCore, TestReqParamsAPI, TestRestoreCore, TestSQLHandlerNonCloud, TestSolrConfigHandlerCloud, LoggingHandlerTest, MetricsHandlerTest, MetricsHistoryHandlerTest, PropertiesRequestHandlerTest, SecurityConfHandlerTest, SegmentsInfoRequestHandlerTest, ShowFileRequestHandlerTest, TestConfigsApi, TestCoreAdminApis, ThreadDumpHandlerTest, BadComponentTest, DebugComponentTest, DistributedDebugComponentTest, DistributedFacetExistsSmallTest, DistributedFacetPivotLongTailTest, DistributedSuggestComponentTest, FacetPivotSmallTest, MoreLikeThisComponentTest, PhrasesIdentificationComponentTest, QueryElevationComponentTest, TermVectorComponentDistributedTest, TestTrackingShardHandlerFactory, JavabinLoaderTest, EmbeddedSolrNoSerializeTest, RandomizedTaggerTest, XmlInterpolationTest, FastVectorHighlighterTest, HighlighterMaxOffsetTest, TestSlowCompositeReaderWrapper, CheckHdfsIndexTest, TestDynamicFieldResource, TestFieldTypeCollectionResource, TestSchemaResource, TestSchemaSimilarityResource, TestSchemaVersionResource, TestSerializedLuceneMatchVersion, TestManagedSynonymFilterFactory, TestManagedSynonymGraphFilterFactory, DocValuesMultiTest, IndexSchemaTest, NotRequiredUniqueKeyTest, NumericFieldsTest, OpenExchangeRatesOrgProviderTest, SchemaApiFailureTest, TestBinaryField, TestBulkSchemaConcurrent, TestHalfAndHalfDocValues, TestManagedSchema, TestSchemaManager, CursorMarkTest, LargeFieldTest, QueryParsingTest, RankQueryTest, SortSpecParsingTest, TestCollapseQParserPlugin, TestDocSet, TestElisionMultitermQuery, TestIndexSearcher, TestQueryTypes, TestQueryUtils, TestRangeQuery, TestSolrCachePerf, TestStressRecovery, TestSurroundQueryParser, TestTermsQParserPlugin, TestValueSourceCache, TestXmlQParserPlugin, TestJsonFacets]
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestJsonFacets -Dtests.seed=F5A152BCB3A7AF33 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=mk -Dtests.timezone=GMT -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.00s J2 | TestJsonFacets (suite) <<<
   [junit4]    > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([F5A152BCB3A7AF33]:0)
   [junit4] Completed [868/868 (2!)] on J2 in 7213.10s, 174 tests, 1 error <<< FAILURES!

[...truncated 51878 lines...]

[JENKINS] Lucene-Solr-Tests-master - Build # 4983 - Still Unstable

Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://builds.apache.org/job/Lucene-Solr-Tests-master/4983/

1 tests failed.
FAILED:  org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming

Error Message:
Exception during query

Stack Trace:
java.lang.RuntimeException: Exception during query
	at __randomizedtesting.SeedInfo.seed([F9E7BED92AF4934F:113B133BD91C896F]:0)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1002)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:962)
	at org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming(TestCircuitBreaker.java:161)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
	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:370)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
	at java.base/java.lang.Thread.run(Thread.java:834)
Caused by: org.apache.solr.common.SolrException: Circuit Breakers tripped org.apache.solr.util.circuitbreaker.MemoryCircuitBreaker seenMemory=435213344 allowedMemory=402653184

	at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:325)
	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:212)
	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2624)
	at org.apache.solr.util.TestHarness.query(TestHarness.java:332)
	at org.apache.solr.util.TestHarness.query(TestHarness.java:314)
	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:976)
	... 41 more




Build Log:
[...truncated 12720 lines...]
   [junit4] Suite: org.apache.solr.util.TestCircuitBreaker
   [junit4]   2> 726999 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/server/solr/configsets/_default/conf'
   [junit4]   2> 726999 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
   [junit4]   2> 726999 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.util.TestCircuitBreaker_F9E7BED92AF4934F-001/data-dir-78-001
   [junit4]   2> 727000 WARN  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=29 numCloses=29
   [junit4]   2> 727000 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
   [junit4]   2> 727001 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
   [junit4]   2> 727002 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore
   [junit4]   2> 727010 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 727028 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 727198 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 727905 WARN  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.c.CoreContainer Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/userfiles].  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/userfiles" "write")
   [junit4]   2> 	at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/userfiles" "write")
   [junit4]   2> 	at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
   [junit4]   2> 	at java.security.AccessController.checkPermission(AccessController.java:897) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkPermission(SecurityManager.java:322) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkWrite(SecurityManager.java:752) ~[?:?]
   [junit4]   2> 	at sun.nio.fs.UnixPath.checkWrite(UnixPath.java:824) ~[?:?]
   [junit4]   2> 	at sun.nio.fs.UnixFileSystemProvider.createDirectory(UnixFileSystemProvider.java:377) ~[?:?]
   [junit4]   2> 	at java.nio.file.Files.createDirectory(Files.java:689) ~[?:?]
   [junit4]   2> 	at java.nio.file.Files.createAndCheckIsDirectory(Files.java:796) ~[?:?]
   [junit4]   2> 	at java.nio.file.Files.createDirectories(Files.java:742) ~[?:?]
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:364) [java/:?]
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.<init>(CoreContainer.java:325) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:170) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:810) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:800) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:661) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:650) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestCircuitBreaker.setUpClass(TestCircuitBreaker.java:57) [test/:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:882) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:834) [?:?]
   [junit4]   2> 727907 WARN  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.f.DistribPackageStore Unable to create [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/filestore] directory in SOLR_HOME [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr].  Features requiring this directory may fail.
   [junit4]   2>           => java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.base/java.security.AccessControlContext.checkPermission(AccessControlContext.java:472)
   [junit4]   2> java.security.AccessControlException: access denied ("java.io.FilePermission" "/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/filestore" "write")
   [junit4]   2> 	at java.security.AccessControlContext.checkPermission(AccessControlContext.java:472) ~[?:?]
   [junit4]   2> 	at java.security.AccessController.checkPermission(AccessController.java:897) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkPermission(SecurityManager.java:322) ~[?:?]
   [junit4]   2> 	at java.lang.SecurityManager.checkWrite(SecurityManager.java:752) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdir(File.java:1323) ~[?:?]
   [junit4]   2> 	at java.io.File.mkdirs(File.java:1355) ~[?:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.ensurePackageStoreDir(DistribPackageStore.java:560) [java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.DistribPackageStore.<init>(DistribPackageStore.java:80) [java/:?]
   [junit4]   2> 	at org.apache.solr.filestore.PackageStoreAPI.<init>(PackageStoreAPI.java:78) [java/:?]
   [junit4]   2> 	at org.apache.solr.core.CoreContainer.load(CoreContainer.java:664) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:171) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:143) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:149) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestHarness.<init>(TestHarness.java:112) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.createCore(SolrTestCaseJ4.java:810) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:800) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:661) [java/:?]
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.initCore(SolrTestCaseJ4.java:650) [java/:?]
   [junit4]   2> 	at org.apache.solr.util.TestCircuitBreaker.setUpClass(TestCircuitBreaker.java:57) [test/:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]
   [junit4]   2> 	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
   [junit4]   2> 	at java.lang.reflect.Method.invoke(Method.java:566) ~[?:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:882) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64) [java/:?]
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54) [java/:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826) [randomizedtesting-runner-2.7.6.jar:?]
   [junit4]   2> 	at java.lang.Thread.run(Thread.java:834) [?:?]
   [junit4]   2> 727908 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
   [junit4]   2> 727910 WARN  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3ff1a285[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 727911 WARN  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3ff1a285[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 727914 WARN  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5b9d0fef[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 727914 WARN  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5b9d0fef[provider=null,keyStore=null,trustStore=null]
   [junit4]   2> 727915 WARN  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.c.CoreContainer Not all security plugins configured!  authentication=disabled authorization=disabled.  Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external.  See https://s.apache.org/solrsecurity for more info
   [junit4]   2> 727958 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cf88def
   [junit4]   2> 727969 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cf88def
   [junit4]   2> 727969 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cf88def
   [junit4]   2> 727971 INFO  (coreLoadExecutor-7510-thread-1) [     ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
   [junit4]   2> 727976 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
   [junit4]   2> 727986 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from instancedir /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1, trusted=true
   [junit4]   2> 727994 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Schema name=test
   [junit4]   2> 728126 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
   [junit4]   2> 728138 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3cf88def
   [junit4]   2> 728138 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.util.TestCircuitBreaker_F9E7BED92AF4934F-001/data-dir-78-001/]
   [junit4]   2> 728140 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=20, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
   [junit4]   2> 728204 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
   [junit4]   2> 728204 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
   [junit4]   2> 728205 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=40, maxMergedSegmentMB=57.0283203125, floorSegmentMB=0.4501953125, forceMergeDeletesPctAllowed=11.320319595719408, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0, deletesPctAllowed=42.89781583932057
   [junit4]   2> 728206 WARN  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.r.ManagedResourceStorage Cannot write to config directory /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/core/src/test-files/solr/collection1/conf ; switching to use InMemory storage instead.
   [junit4]   2> 728207 INFO  (coreLoadExecutor-7510-thread-1) [    x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
   [junit4]   2> 728208 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728208 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.SolrTestCaseJ4 ####initCore end
   [junit4]   2> 728209 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 1
   [junit4]   2> 728211 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728211 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728212 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728213 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728213 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728226 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728227 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 13
   [junit4]   2> 728227 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728228 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728228 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728229 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728229 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728230 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728241 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728242 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728243 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728243 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728243 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728244 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728244 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728245 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728256 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728257 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728257 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728258 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728258 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728259 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728259 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728260 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728271 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728271 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728272 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728273 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728273 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728273 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728274 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728274 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728286 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728286 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728287 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728287 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728288 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728288 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728289 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728289 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728300 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728301 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728301 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728302 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728302 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728303 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728303 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728304 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728315 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728315 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728316 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728316 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728317 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728317 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728318 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728318 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728329 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728330 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728330 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728331 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728331 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728332 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728332 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728333 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728344 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728344 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728345 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728345 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728346 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728346 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728347 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728347 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728358 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728358 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 10
   [junit4]   2> 728359 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728360 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728360 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728361 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728361 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728362 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728373 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728373 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728374 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728374 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728375 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728375 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728376 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728376 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728388 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728388 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728389 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728390 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728390 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728391 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728391 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728392 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728403 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728403 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728404 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728404 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728405 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728406 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728406 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728407 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728418 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728419 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728419 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728420 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728420 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728421 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728421 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728422 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728433 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728434 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728434 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728435 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728435 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728436 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728436 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728437 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728448 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728449 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728450 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728450 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728450 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728451 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728452 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728452 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728463 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728464 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728465 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728465 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728466 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728466 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728467 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728467 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728479 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728479 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728480 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728481 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728481 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728482 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728482 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728483 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728494 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728495 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728496 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728496 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728497 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728497 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[1]} 0 0
   [junit4]   2> 728498 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[2]} 0 0
   [junit4]   2> 728498 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{add=[3]} 0 0
   [junit4]   2> 728510 INFO  (searcherExecutor-7512-thread-1-processing-x:collection1) [    x:collection1 ] o.a.s.c.SolrCore [collection1]  Registered new searcher autowarm time: 0 ms
   [junit4]   2> 728510 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [    x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1]  webapp=null path=null params={}{commit=} 0 11
   [junit4]   2> 728514 INFO  (TEST-TestCircuitBreaker.testResponseWithCBTiming-seed#[F9E7BED92AF4934F]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testResponseWithCBTiming
   [junit4]   2> 728515 INFO  (TEST-TestCircuitBreaker.testResponseWithCBTiming-seed#[F9E7BED92AF4934F]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=*:*&wt=xml&debugQuery=true} status=503 QTime=1
   [junit4]   2> 728516 ERROR (TEST-TestCircuitBreaker.testResponseWithCBTiming-seed#[F9E7BED92AF4934F]) [     ] o.a.s.SolrTestCaseJ4 REQUEST FAILED: q=*:*&wt=xml&debugQuery=true:org.apache.solr.common.SolrException: Circuit Breakers tripped org.apache.solr.util.circuitbreaker.MemoryCircuitBreaker seenMemory=435213344 allowedMemory=402653184
   [junit4]   2> 
   [junit4]   2> 	at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:325)
   [junit4]   2> 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:212)
   [junit4]   2> 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2624)
   [junit4]   2> 	at org.apache.solr.util.TestHarness.query(TestHarness.java:332)
   [junit4]   2> 	at org.apache.solr.util.TestHarness.query(TestHarness.java:314)
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:976)
   [junit4]   2> 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:962)
   [junit4]   2> 	at org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming(TestCircuitBreaker.java:161)
   [junit4]   2> 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]   2> 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
   [junit4]   2> 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
   [junit4]   2> 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
   [junit4]   2> 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
   [junit4]   2> 	at java.base/java.lang.Thread.run(Thread.java:834)
   [junit4]   2> 
   [junit4]   2> 728517 INFO  (TEST-TestCircuitBreaker.testResponseWithCBTiming-seed#[F9E7BED92AF4934F]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testResponseWithCBTiming
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=TestCircuitBreaker -Dtests.method=testResponseWithCBTiming -Dtests.seed=F9E7BED92AF4934F -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=en-CA -Dtests.timezone=Etc/GMT-0 -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
   [junit4] ERROR   0.01s J0 | TestCircuitBreaker.testResponseWithCBTiming <<<
   [junit4]    > Throwable #1: java.lang.RuntimeException: Exception during query
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([F9E7BED92AF4934F:113B133BD91C896F]:0)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:1002)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:962)
   [junit4]    > 	at org.apache.solr.util.TestCircuitBreaker.testResponseWithCBTiming(TestCircuitBreaker.java:161)
   [junit4]    > 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]    > 	at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
   [junit4]    > 	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]    > 	at java.base/java.lang.reflect.Method.invoke(Method.java:566)
   [junit4]    > 	at java.base/java.lang.Thread.run(Thread.java:834)
   [junit4]    > Caused by: org.apache.solr.common.SolrException: Circuit Breakers tripped org.apache.solr.util.circuitbreaker.MemoryCircuitBreaker seenMemory=435213344 allowedMemory=402653184
   [junit4]    > 	at org.apache.solr.handler.component.SearchHandler.handleRequestBody(SearchHandler.java:325)
   [junit4]    > 	at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:212)
   [junit4]    > 	at org.apache.solr.core.SolrCore.execute(SolrCore.java:2624)
   [junit4]    > 	at org.apache.solr.util.TestHarness.query(TestHarness.java:332)
   [junit4]    > 	at org.apache.solr.util.TestHarness.query(TestHarness.java:314)
   [junit4]    > 	at org.apache.solr.SolrTestCaseJ4.assertQ(SolrTestCaseJ4.java:976)
   [junit4]    > 	... 41 more
   [junit4]   2> 728522 INFO  (TEST-TestCircuitBreaker.testBuildingMemoryPressure-seed#[F9E7BED92AF4934F]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testBuildingMemoryPressure
   [junit4]   2> 728523 INFO  (TestCircuitBreaker-7516-thread-1) [     ] o.a.s.u.TestCircuitBreaker BuildingUpMemoryPressureCircuitBreaker: Returning unblocking value for count 0
   [junit4]   2> 728523 INFO  (TestCircuitBreaker-7516-thread-3) [     ] o.a.s.u.TestCircuitBreaker BuildingUpMemoryPressureCircuitBreaker: Returning unblocking value for count 2
   [junit4]   2> 728523 INFO  (TestCircuitBreaker-7516-thread-2) [     ] o.a.s.u.TestCircuitBreaker BuildingUpMemoryPressureCircuitBreaker: Returning unblocking value for count 1
   [junit4]   2> 728524 INFO  (TestCircuitBreaker-7516-thread-4) [     ] o.a.s.u.TestCircuitBreaker BuildingUpMemoryPressureCircuitBreaker: Returning unblocking value for count 3
   [junit4]   2> 728524 INFO  (TestCircuitBreaker-7516-thread-5) [     ] o.a.s.u.TestCircuitBreaker Blocking query from BuildingUpMemoryPressureCircuitBreaker for count 4
   [junit4]   2> 728524 INFO  (TestCircuitBreaker-7516-thread-5) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
   [junit4]   2> 728534 INFO  (TestCircuitBreaker-7516-thread-2) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=11
   [junit4]   2> 728534 INFO  (TestCircuitBreaker-7516-thread-3) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=11
   [junit4]   2> 728534 INFO  (TestCircuitBreaker-7516-thread-1) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=11
   [junit4]   2> 728534 INFO  (TestCircuitBreaker-7516-thread-4) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} hits=0 status=0 QTime=10
   [junit4]   2> 728535 INFO  (TEST-TestCircuitBreaker.testBuildingMemoryPressure-seed#[F9E7BED92AF4934F]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testBuildingMemoryPressure
   [junit4]   2> 728539 INFO  (TEST-TestCircuitBreaker.testCBFakeMemoryPressure-seed#[F9E7BED92AF4934F]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testCBFakeMemoryPressure
   [junit4]   2> 728539 INFO  (TEST-TestCircuitBreaker.testCBFakeMemoryPressure-seed#[F9E7BED92AF4934F]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
   [junit4]   2> 728540 INFO  (TEST-TestCircuitBreaker.testCBFakeMemoryPressure-seed#[F9E7BED92AF4934F]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testCBFakeMemoryPressure
   [junit4]   2> 728543 INFO  (TEST-TestCircuitBreaker.testCBAlwaysTrips-seed#[F9E7BED92AF4934F]) [     ] o.a.s.SolrTestCaseJ4 ###Starting testCBAlwaysTrips
   [junit4]   2> 728544 INFO  (TEST-TestCircuitBreaker.testCBAlwaysTrips-seed#[F9E7BED92AF4934F]) [     ] o.a.s.c.S.Request [collection1]  webapp=null path=null params={q=name:"john+smith"&qt=&start=0&rows=20&version=2.2} status=503 QTime=0
   [junit4]   2> 728544 INFO  (TEST-TestCircuitBreaker.testCBAlwaysTrips-seed#[F9E7BED92AF4934F]) [     ] o.a.s.SolrTestCaseJ4 ###Ending testCBAlwaysTrips
   [junit4]   2> 728544 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.SolrTestCaseJ4 ###deleteCore
   [junit4]   2> 728545 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1752314308
   [junit4]   2> 728545 INFO  (coreCloseExecutor-7518-thread-1) [     ] o.a.s.c.SolrCore [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@6d23c165
   [junit4]   2> 728545 INFO  (coreCloseExecutor-7518-thread-1) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.collection1 tag=SolrCore@6d23c165
   [junit4]   2> 728545 INFO  (coreCloseExecutor-7518-thread-1) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6a18fe66: rootName = null, domain = solr.core.collection1, service url = null, agent id = null] for registry solr.core.collection1/com.codahale.metrics.MetricRegistry@4912e144
   [junit4]   2> 728562 INFO  (coreCloseExecutor-7518-thread-1) [     ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close()  ... SKIPPED (unnecessary).
   [junit4]   2> 728567 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
   [junit4]   2> 728567 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7d1d943a: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@3da02a43
   [junit4]   2> 728574 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
   [junit4]   2> 728574 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@444e8db9: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@6ca149d1
   [junit4]   2> 728578 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
   [junit4]   2> 728578 INFO  (SUITE-TestCircuitBreaker-seed#[F9E7BED92AF4934F]-worker) [     ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@140f23fc: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@260ed765
   [junit4]   2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene-Solr-Tests-master/solr/build/solr-core/test/J0/temp/solr.util.TestCircuitBreaker_F9E7BED92AF4934F-001
   [junit4]   2> NOTE: test params are: codec=Asserting(Lucene86): {range_facet_l_dv=PostingsFormat(name=Direct), multiDefault=FST50, _root_=PostingsFormat(name=LuceneVarGapFixedInterval), intDefault=PostingsFormat(name=LuceneVarGapFixedInterval), title=PostingsFormat(name=LuceneVarGapFixedInterval), title_lettertok=PostingsFormat(name=Direct), range_facet_l=FST50, title_stringNoNorms=FST50, title_stemmed=PostingsFormat(name=LuceneVarGapFixedInterval), inStock_b1=PostingsFormat(name=LuceneVarGapDocFreqInterval), name=PostingsFormat(name=Direct), id_i1=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=Direct), range_facet_i_dv=FST50, text=PostingsFormat(name=LuceneVarGapDocFreqInterval), timestamp=FST50}, docValues:{range_facet_l_dv=DocValuesFormat(name=Asserting), range_facet_i_dv=DocValuesFormat(name=Lucene80), intDvoDefault=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1957, maxMBSortInHeap=6.975290213788418, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=en-CA, timezone=Etc/GMT-0
   [junit4]   2> NOTE: Linux 4.4.0-170-generic amd64/Oracle Corporation 11.0.4 (64-bit)/cpus=8,threads=1,free=354960264,total=528482304
   [junit4]   2> NOTE: All tests run in this JVM: [CustomTermsComponentTest, TestPostingsSolrHighlighter, ConnectionManagerTest, PrimitiveFieldTypeTest, TestPerFieldSimilarity, IndexSchemaRuntimeFieldTest, SolrCoreTest, OverseerSolrResponseUnsafeSerializationTest, TestDistributedStatsComponentCardinality, TestCloudRecovery2, HdfsRecoveryZkTest, InfixSuggestersTest, TestLMJelinekMercerSimilarityFactory, NotRequiredUniqueKeyTest, CoreAdminOperationTest, TestSQLHandlerNonCloud, TestBulkSchemaAPI, TestJsonFacets, DateRangeFieldTest, TestBlobHandler, SolrCoreMetricManagerTest, OutOfBoxZkACLAndCredentialsProvidersTest, SolrRequestParserTest, TestCollectionAPIs, QueryElevationComponentTest, TestDynamicLoadingUrl, HdfsWriteToMultipleCollectionsTest, TestElisionMultitermQuery, DefaultValueUpdateProcessorTest, CopyFieldTest, TestDynamicFieldResource, SolrIndexSplitterTest, TlogReplayBufferedWhileIndexingTest, TestSort, BaseCdcrDistributedZkTest, TestInitQParser, TestRangeQuery, TestSchemaResource, TestMinMaxOnMultiValuedField, TestFilteredDocIdSet, TestCollectionAPI, TestMiniSolrCloudClusterSSL, TestDistributedMissingSort, TestExactSharedStatsCache, FieldAnalysisRequestHandlerTest, TestInPlaceUpdatesRequiredField, TestMaxTokenLenTokenizer, TestHashPartitioner, SampleTest, TestRebalanceLeaders, TestLegacyNumericUtils, JavabinLoaderTest, TestSolrCloudWithSecureImpersonation, TestHdfsUpdateLog, HighlighterMaxOffsetTest, TestFieldCache, TestInfoStreamLogging, SolrCmdDistributorTest, TestManagedSchemaThreadSafety, EchoParamsTest, TestSolrCloudSnapshots, SSLMigrationTest, TestReloadDeadlock, JWTVerificationkeyResolverTest, TestBM25SimilarityFactory, MultiThreadedOCPTest, TestCircuitBreaker]
   [junit4] Completed [269/868 (1!)] on J0 in 1.62s, 4 tests, 1 error <<< FAILURES!

[...truncated 53977 lines...]