You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/04/02 15:51:14 UTC
[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk1.8.0_201) - Build # 2631
- Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/2631/
Java: 64bit/jdk1.8.0_201 -XX:-UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.MathExpressionTest.testZipFDistribution
Error Message:
Zipf distribution not descending!!!
Stack Trace:
java.lang.Exception: Zipf distribution not descending!!!
at __randomizedtesting.SeedInfo.seed([DFDBA77D07ECA7EE:FB6ECA591044AFC6]:0)
at org.apache.solr.client.solrj.io.stream.MathExpressionTest.testZipFDistribution(MathExpressionTest.java:3766)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 17050 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.MathExpressionTest
[junit4] 2> 3081 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 3081 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 3095 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/data-dir-1-001
[junit4] 2> 3212 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 3256 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.e.j.u.log Logging initialized @3290ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 3266 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 3299 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/tempDir-001
[junit4] 2> 3302 WARN (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer TEST_HOME() does not exist - solrj test?
[junit4] 2> 3311 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 3315 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 3315 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 3415 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:36063
[junit4] 2> 3415 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:36063
[junit4] 2> 3417 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36063
[junit4] 2> 3418 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36063
[junit4] 2> 3499 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3533 INFO (zkConnectionManagerCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3534 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3571 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3675 INFO (zkConnectionManagerCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3676 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 3678 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 3685 INFO (zkConnectionManagerCallback-7-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 3685 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4119 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 4119 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 4119 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 4120 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4120 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 4120 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4120 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4120 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4120 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4120 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4121 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 4121 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4137 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 4142 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 4145 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 4145 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 1.8.0_201-b09
[junit4] 2> 4229 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4230 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4237 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4237 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4241 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4242 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4273 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4273 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4274 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4274 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4275 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4275 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4287 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5339341c{/solr,null,AVAILABLE}
[junit4] 2> 4287 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5638f1e2{/solr,null,AVAILABLE}
[junit4] 2> 4287 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7e285f38{/solr,null,AVAILABLE}
[junit4] 2> 4287 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5beda906{/solr,null,AVAILABLE}
[junit4] 2> 4319 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@66e4a97c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:40519}
[junit4] 2> 4320 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.Server Started @4356ms
[junit4] 2> 4320 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40519}
[junit4] 2> 4333 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@54d9368e{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:37355}
[junit4] 2> 4333 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.Server Started @4370ms
[junit4] 2> 4334 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=37355}
[junit4] 2> 4335 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6f654e59{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:39731}
[junit4] 2> 4335 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.Server Started @4372ms
[junit4] 2> 4335 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39731}
[junit4] 2> 4347 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4d937867{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:33581}
[junit4] 2> 4347 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.Server Started @4384ms
[junit4] 2> 4347 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=33581}
[junit4] 2> 4421 ERROR (jetty-launcher-8-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4421 ERROR (jetty-launcher-8-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4421 ERROR (jetty-launcher-8-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4421 ERROR (jetty-launcher-8-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4421 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4421 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4421 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4421 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4421 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 4421 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 4422 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 4422 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 4425 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4426 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4426 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 4426 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4426 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4426 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 4426 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 4426 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-02T15:38:46.571Z
[junit4] 2> 4427 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 4427 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-02T15:38:46.572Z
[junit4] 2> 4427 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-02T15:38:46.572Z
[junit4] 2> 4427 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-02T15:38:46.572Z
[junit4] 2> 4428 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4428 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4429 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4447 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4490 INFO (zkConnectionManagerCallback-10-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4490 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4518 INFO (zkConnectionManagerCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4518 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4528 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4528 INFO (zkConnectionManagerCallback-12-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4528 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4528 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4529 INFO (zkConnectionManagerCallback-15-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4529 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4536 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 4540 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 5725 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 5725 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 5728 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 5728 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 5983 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@710277c5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5983 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@710277c5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5989 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@fac98fb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5989 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@fac98fb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5991 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@21680bf9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5991 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@21680bf9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5994 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@20147dbf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 5994 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@20147dbf[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6249 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@f3c9360[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6249 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6804a2ee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6249 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@f3c9360[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6249 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6804a2ee[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6263 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36063/solr
[junit4] 2> 6268 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36063/solr
[junit4] 2> 6303 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@414be4aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6303 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@414be4aa[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6304 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36063/solr
[junit4] 2> 6306 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6307 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6311 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@22ceb62b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6311 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@22ceb62b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 6316 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6318 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:36063/solr
[junit4] 2> 6318 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6319 INFO (zkConnectionManagerCallback-41-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6319 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6320 INFO (zkConnectionManagerCallback-38-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6321 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6322 INFO (zkConnectionManagerCallback-36-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6322 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6324 INFO (zkConnectionManagerCallback-44-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6324 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6433 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6435 INFO (zkConnectionManagerCallback-46-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6435 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6534 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6551 INFO (zkConnectionManagerCallback-50-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6551 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6622 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:37355_solr
[junit4] 2> 6626 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.c.Overseer Overseer (id=72153727211143180-127.0.0.1:37355_solr-n_0000000000) starting
[junit4] 2> 6642 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6648 INFO (zkConnectionManagerCallback-54-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6648 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6669 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39731_solr
[junit4] 2> 6704 INFO (zkCallback-53-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6711 INFO (zkCallback-49-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6743 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 6765 INFO (zkConnectionManagerCallback-60-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 6765 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 6774 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 6783 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40519_solr as DOWN
[junit4] 2> 6783 INFO (OverseerStateUpdate-72153727211143180-127.0.0.1:37355_solr-n_0000000000) [n:127.0.0.1:37355_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:37355_solr
[junit4] 2> 6790 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 6799 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37355_solr as DOWN
[junit4] 2> 6800 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 6801 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40519_solr
[junit4] 2> 6803 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 6803 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37355_solr
[junit4] 2> 6825 WARN (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_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> 6840 INFO (zkCallback-49-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 6879 INFO (zkCallback-53-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 6880 INFO (zkCallback-59-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (3)
[junit4] 2> 6880 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 6881 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 6901 WARN (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_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> 6904 WARN (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_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> 6986 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 7022 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:33581_solr as DOWN
[junit4] 2> 7023 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 7023 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33581_solr
[junit4] 2> 7024 INFO (zkCallback-59-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 7025 INFO (zkCallback-49-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 7027 INFO (zkCallback-53-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 7034 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 7035 WARN (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_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> 7041 INFO (zkCallback-45-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 7255 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 7283 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 7297 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 7300 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 7629 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40519.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7644 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33581.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7646 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39731.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7648 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37355.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7653 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40519.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7653 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39731.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7653 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39731.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7653 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40519.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7654 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37355.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7654 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37355.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7654 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33581.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7654 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33581.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 7707 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:40519_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/tempDir-001/node1/.
[junit4] 2> 7772 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:37355_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/tempDir-001/node2/.
[junit4] 2> 7773 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:33581_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/tempDir-001/node3/.
[junit4] 2> 7774 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:39731_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/tempDir-001/node4/.
[junit4] 2> 7883 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 7895 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 7901 INFO (zkConnectionManagerCallback-73-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 7901 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 7904 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 7905 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:36063/solr ready
[junit4] 2> 8118 INFO (qtp411192030-212) [ ] o.e.j.u.TypeUtil JVM Runtime does not support Modules
[junit4] 2> 8263 INFO (qtp411192030-212) [n:127.0.0.1:37355_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 8277 INFO (OverseerThreadFactory-24-thread-1-processing-n:127.0.0.1:37355_solr) [n:127.0.0.1:37355_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 8431 INFO (OverseerStateUpdate-72153727211143180-127.0.0.1:37355_solr-n_0000000000) [n:127.0.0.1:37355_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"collection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:37355/solr",
[junit4] 2> "node_name":"127.0.0.1:37355_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 8439 INFO (OverseerStateUpdate-72153727211143180-127.0.0.1:37355_solr-n_0000000000) [n:127.0.0.1:37355_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"collection1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:33581/solr",
[junit4] 2> "node_name":"127.0.0.1:33581_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 8709 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 8709 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 8764 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 8793 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 8896 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 8897 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 8924 WARN (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 8931 WARN (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieIntField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 8934 WARN (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieFloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 8935 WARN (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieFloatField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 8939 WARN (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieLongField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 8944 WARN (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDoubleField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 9224 WARN (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.TrieDateField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 9489 WARN (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 9495 WARN (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrResourceLoader Solr loaded a deprecated plugin/analysis class [solr.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 9529 WARN (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 9529 WARN (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 9537 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 9545 WARN (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 9545 WARN (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 9546 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 9550 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 9554 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 9571 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_33581.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 9582 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/tempDir-001/node3/./collection1_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/tempDir-001/node3/./collection1_shard2_replica_n2/data/]
[junit4] 2> 9591 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37355.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@25a4fb4b
[junit4] 2> 9592 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/tempDir-001/node2/./collection1_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001/tempDir-001/node2/./collection1_shard1_replica_n1/data/]
[junit4] 2> 10405 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 10405 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 10412 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 10412 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 10422 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 10422 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 10422 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 10422 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 10440 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@322f2f8d[collection1_shard1_replica_n1] main]
[junit4] 2> 10440 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4a03c02f[collection1_shard2_replica_n2] main]
[junit4] 2> 10453 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 10454 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 10473 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 10473 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 10497 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 10504 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 10505 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1662875790370406400
[junit4] 2> 10505 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1662875790370406400
[junit4] 2> 10520 INFO (searcherExecutor-41-thread-1-processing-n:127.0.0.1:33581_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [collection1_shard2_replica_n2] Registered new searcher Searcher@4a03c02f[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 10523 INFO (searcherExecutor-42-thread-1-processing-n:127.0.0.1:37355_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [collection1_shard1_replica_n1] Registered new searcher Searcher@322f2f8d[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 10543 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 10543 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 10549 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard2
[junit4] 2> 10549 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/collection1/leaders/shard1
[junit4] 2> 10562 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 10562 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 10563 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/
[junit4] 2> 10565 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 10565 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 10565 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:33581/solr/collection1_shard2_replica_n2/
[junit4] 2> 10568 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 10569 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 10569 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 10572 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard1/leader after winning as /collections/collection1/leader_elect/shard1/election/72153727211143180-core_node3-n_0000000000
[junit4] 2> 10572 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:33581/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 10572 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/collection1/leaders/shard2/leader after winning as /collections/collection1/leader_elect/shard2/election/72153727211143179-core_node4-n_0000000000
[junit4] 2> 10585 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:33581/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 10585 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 10701 INFO (zkCallback-49-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 10701 INFO (zkCallback-45-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 10704 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 10733 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 10742 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=collection1_shard1_replica_n1&action=CREATE&numShards=2&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2069
[junit4] 2> 10748 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=collection1_shard2_replica_n2&action=CREATE&numShards=2&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2064
[junit4] 2> 10792 INFO (qtp411192030-212) [n:127.0.0.1:37355_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 10844 INFO (zkCallback-49-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 10844 INFO (zkCallback-49-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 10855 INFO (zkCallback-45-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 10855 INFO (zkCallback-45-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json] for collection [collection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 10858 INFO (qtp411192030-212) [n:127.0.0.1:37355_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=2626
[junit4] 2> 10865 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):45
[junit4] 2> 10880 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 10945 INFO (TEST-MathExpressionTest.testGtestDataSet-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGtestDataSet
[junit4] 2> 11240 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 11241 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/&wt=javabin&version=2} status=0 QTime=19
[junit4] 2> 11262 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791164178432,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11262 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11262 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11265 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=3
[junit4] 2> 11270 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791172567040,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11270 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11271 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11274 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/collection1/terms/shard1 to Terms{values={core_node3=1}, version=1}
[junit4] 2> 11274 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2} status=0 QTime=310
[junit4] 2> 11285 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=let(echo%3Dtrue,++++a%3Darray(1,1,2,3,4,5,6,7,9,10,11,12),+++++b%3Darray(1,1,2,3,4,5,6,7,1,1,1,1),+++++gtest%3DgtestDataSet(a,+b))&wt=json&version=2.2} status=0 QTime=4
[junit4] 2> 11335 INFO (TEST-MathExpressionTest.testGtestDataSet-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testGtestDataSet
[junit4] 2> 11409 INFO (TEST-MathExpressionTest.testPrimes-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPrimes
[junit4] 2> 11455 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:33581/solr/collection1_shard2_replica_n2/&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 11470 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791382282240,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11470 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11472 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11472 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33581/solr/collection1_shard2_replica_n2/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=3
[junit4] 2> 11476 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791388573696,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11476 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11477 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11480 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2} status=0 QTime=67
[junit4] 2> 11485 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=primes(10,+0)&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 11490 INFO (TEST-MathExpressionTest.testPrimes-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPrimes
[junit4] 2> 11582 INFO (TEST-MathExpressionTest.testLoess-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testLoess
[junit4] 2> 11593 INFO (qtp411192030-212) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:33581/solr/collection1_shard2_replica_n2/&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 11597 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791515451392,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11597 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11598 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11598 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33581/solr/collection1_shard2_replica_n2/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=1
[junit4] 2> 11600 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791518597120,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11600 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11600 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11601 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 11606 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=let(echo%3Dtrue,++++a%3Darray(0,1,2,3,4,5,6,7),++++fit%3Dloess(a),+++++der%3Dderivative(fit))&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 11618 INFO (TEST-MathExpressionTest.testLoess-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testLoess
[junit4] 2> 11649 INFO (TEST-MathExpressionTest.testPivot-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPivot
[junit4] 2> 11662 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/&wt=javabin&version=2} status=0 QTime=6
[junit4] 2> 11684 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791606677504,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11684 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11685 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11685 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=2
[junit4] 2> 11696 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791619260416,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11696 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11697 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11697 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2} status=0 QTime=45
[junit4] 2> 11708 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=let(echo%3Dtrue,+++++++++++++++a%3Dlist(tuple(fx%3Dx1,+fy%3Df1,+fv%3Dadd(1,1)),+++++++++++++++++++++++tuple(fx%3Dx1,+fy%3Df2,+fv%3Dadd(1,3)),+++++++++++++++++++++++tuple(fx%3Dx2,+fy%3Df1,+fv%3Dadd(1,7)),+++++++++++++++++++++++tuple(fx%3Dx3,+fy%3Df1,+fv%3Dadd(1,4)),++++++++++++++++++++++tuple(fx%3Dx3,+fy%3Df3,+fv%3Dadd(1,7))),++++b%3Dpivot(a,+"fx",+"fy",+"fv"),+++++++++++++++c%3DgetRowLabels(b),+++++++++++++++d%3DgetColumnLabels(b))&wt=json&version=2.2} status=0 QTime=2
[junit4] 2> 11713 INFO (TEST-MathExpressionTest.testPivot-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPivot
[junit4] 2> 11740 INFO (TEST-MathExpressionTest.testIsNullHaving-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testIsNullHaving
[junit4] 2> 11748 INFO (qtp411192030-212) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:33581/solr/collection1_shard2_replica_n2/&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 11752 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791677980672,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11752 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11754 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11754 INFO (qtp411192030-220) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:33581/solr/collection1_shard2_replica_n2/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=3
[junit4] 2> 11758 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791684272128,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11758 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11759 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11760 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 11765 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=having(list(tuple(a%3Dadd(1,+1)),+tuple(b%3Dadd(1,+2))),+isNull(b))&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 11767 INFO (TEST-MathExpressionTest.testIsNullHaving-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testIsNullHaving
[junit4] 2> 11847 INFO (TEST-MathExpressionTest.testPairSort-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testPairSort
[junit4] 2> 11857 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/&wt=javabin&version=2} status=0 QTime=4
[junit4] 2> 11881 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791813246976,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11882 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11889 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11889 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=8
[junit4] 2> 11891 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791823732736,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 11891 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 11892 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 11892 INFO (qtp411192030-219) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2} status=0 QTime=41
[junit4] 2> 11895 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=let(a%3Darray(4.5,+7.7,+2.1,+2.1,+6.3),+++++++++++++++b%3Darray(1,+2,+3,+4,+5),+++++++++++++++c%3DpairSort(a,+b))&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 11901 INFO (TEST-MathExpressionTest.testPairSort-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testPairSort
[junit4] 2> 11991 INFO (TEST-MathExpressionTest.testCache-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCache
[junit4] 2> 12000 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/&wt=javabin&version=2} status=0 QTime=2
[junit4] 2> 12005 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791943270400,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 12005 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 12012 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 12013 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=9
[junit4] 2> 12015 INFO (qtp411192030-212) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875791953756160,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 12015 INFO (qtp411192030-212) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 12015 INFO (qtp411192030-212) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 12015 INFO (qtp411192030-212) [n:127.0.0.1:37355_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2} status=0 QTime=22
[junit4] 2> 12035 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=putCache("space1",+"key1",+dotProduct(array(2,4,6,8,10,12),array(1,2,3,4,5,6)))&wt=json&version=2.2} status=0 QTime=2
[junit4] 2> 12052 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=getCache("space1",+"key1")&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 12069 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=listCache("space1")&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 12083 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=listCache()&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 12091 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=removeCache("space1",+"key1")&wt=json&version=2.2} status=0 QTime=1
[junit4] 2> 12106 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/stream params={indent=off&expr=listCache("space1")&wt=json&version=2.2} status=0 QTime=0
[junit4] 2> 12108 INFO (TEST-MathExpressionTest.testCache-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCache
[junit4] 2> 12160 INFO (TEST-MathExpressionTest.testMinMaxScale-seed#[DFDBA77D07ECA7EE]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testMinMaxScale
[junit4] 2> 12178 INFO (qtp1462768363-218) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:37355/solr/collection1_shard1_replica_n1/&wt=javabin&version=2} status=0 QTime=10
[junit4] 2> 12188 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1662875792135159808,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 12188 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 No uncommitted changes. Skipping IW.commit.
[junit4] 2> 12189 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 12189 INFO (qtp1462768363-237) [n:127.0.0.1:33581_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [colle
[...truncated too long message...]
loser-88-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@75ba4421: rootName = solr_37355, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@11cfc6
[junit4] 2> 24904 INFO (jetty-closer-88-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5f2ccce7: rootName = solr_33581, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@489edb95
[junit4] 2> 24912 INFO (jetty-closer-88-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 24912 INFO (jetty-closer-88-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4be24f2e: rootName = solr_37355, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@d844890
[junit4] 2> 24915 INFO (jetty-closer-88-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 24915 INFO (jetty-closer-88-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@434d2e90: rootName = solr_33581, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@d844890
[junit4] 2> 24920 INFO (jetty-closer-88-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 24920 INFO (jetty-closer-88-thread-3) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2296257c: rootName = solr_37355, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7a361244
[junit4] 2> 24920 INFO (jetty-closer-88-thread-3) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 24922 INFO (jetty-closer-88-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 24922 INFO (jetty-closer-88-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@652cef10: rootName = solr_33581, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7a361244
[junit4] 2> 24922 INFO (jetty-closer-88-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 24943 INFO (closeThreadPool-103-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72153727211143180-127.0.0.1:37355_solr-n_0000000000) closing
[junit4] 2> 24999 INFO (OverseerStateUpdate-72153727211143180-127.0.0.1:37355_solr-n_0000000000) [n:127.0.0.1:37355_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:37355_solr
[junit4] 2> 25065 INFO (OverseerAutoScalingTriggerThread-72153727211143180-127.0.0.1:37355_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 25068 INFO (closeThreadPool-103-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72153727211143180-127.0.0.1:37355_solr-n_0000000000) closing
[junit4] 2> 25076 INFO (jetty-closer-88-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@66e4a97c{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 25076 INFO (jetty-closer-88-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@7e285f38{/solr,null,UNAVAILABLE}
[junit4] 2> 25077 INFO (jetty-closer-88-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 25101 INFO (jetty-closer-88-thread-3) [ ] o.a.s.c.Overseer Overseer (id=72153727211143180-127.0.0.1:37355_solr-n_0000000000) closing
[junit4] 2> 25111 INFO (jetty-closer-88-thread-3) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@54d9368e{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 25111 INFO (jetty-closer-88-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5339341c{/solr,null,UNAVAILABLE}
[junit4] 2> 25111 INFO (jetty-closer-88-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 25208 INFO (jetty-closer-88-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4d937867{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:0}
[junit4] 2> 25209 INFO (jetty-closer-88-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5638f1e2{/solr,null,UNAVAILABLE}
[junit4] 2> 25209 INFO (jetty-closer-88-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 25211 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 25452 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterprops.json
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 3 /solr/collections/collection1/terms/shard2
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 2 /solr/collections/collection1/collectionprops.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/collections/collection1/state.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 3 /solr/overseer_elect/election/72153727211143180-127.0.0.1:37355_solr-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 21 /solr/live_nodes
[junit4] 2> 18 /solr/overseer/queue
[junit4] 2> 11 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 25459 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:36063
[junit4] 2> 25459 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36063
[junit4] 2> 25459 INFO (SUITE-MathExpressionTest-seed#[DFDBA77D07ECA7EE]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36063
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-solrj/test/J3/temp/solr.client.solrj.io.stream.MathExpressionTest_DFDBA77D07ECA7EE-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene84): {multiDefault=Lucene84, price_f=Lucene84, intDefault=BlockTreeOrds(blocksize=128), test_dt=PostingsFormat(name=Asserting), price_i=PostingsFormat(name=LuceneVarGapDocFreqInterval), test_t=PostingsFormat(name=LuceneVarGapDocFreqInterval), col_s=BlockTreeOrds(blocksize=128), loc_p_1_d1=PostingsFormat(name=Asserting), order_i=Lucene84, _version_=BlockTreeOrds(blocksize=128), loc_p_0_d1=BlockTreeOrds(blocksize=128), id=PostingsFormat(name=LuceneVarGapDocFreqInterval), text=PostingsFormat(name=Asserting), miles_i=BlockTreeOrds(blocksize=128), timestamp=Lucene84}, docValues:{multiDefault=DocValuesFormat(name=Lucene80), price_f=DocValuesFormat(name=Lucene80), intDefault=DocValuesFormat(name=Direct), test_dt=DocValuesFormat(name=Asserting), price_i=DocValuesFormat(name=Lucene80), col_s=DocValuesFormat(name=Direct), loc_p_1_d1=DocValuesFormat(name=Asserting), order_i=DocValuesFormat(name=Lucene80), _version_=DocValuesFormat(name=Direct), loc_p_0_d1=DocValuesFormat(name=Direct), id=DocValuesFormat(name=Lucene80), miles_i=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=94, maxMBSortInHeap=7.817786100912096, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@689c2e02), locale=nl-NL, timezone=Europe/Budapest
[junit4] 2> NOTE: Linux 5.3.0-42-generic amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=16,threads=1,free=354885280,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [NotEvaluatorTest, LessThanEvaluatorTest, TestDelegationTokenRequest, MathExpressionTest]
[junit4] Completed [49/208 (1!)] on J3 in 22.62s, 140 tests, 1 error <<< FAILURES!
[...truncated 51931 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/2631/consoleText
[repro] Revision: 1cf156d7faf2ad545ae5f68a9888ce498d98c44d
[repro] Ant options: "-Dargs=-XX:-UseCompressedOops -XX:+UseConcMarkSweepGC"
[repro] Repro line: ant test -Dtestcase=MathExpressionTest -Dtests.method=testZipFDistribution -Dtests.seed=DFDBA77D07ECA7EE -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nl-NL -Dtests.timezone=Europe/Budapest -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr/solrj
[repro] MathExpressionTest
[repro] ant compile-test
[...truncated 2557 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.MathExpressionTest" -Dtests.showOutput=onerror "-Dargs=-XX:-UseCompressedOops -XX:+UseConcMarkSweepGC" -Dtests.seed=DFDBA77D07ECA7EE -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=nl-NL -Dtests.timezone=Europe/Budapest -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[...truncated 771 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.MathExpressionTest
[repro] Exiting with code 0
[...truncated 43 lines...]
[JENKINS-Experimental-GC] Lucene-Solr-8.x-Linux
(64bit/jdk-15-ea+16) - Build # 2632 - Failure!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/2632/
Java: 64bit/jdk-15-ea+16 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
2 tests failed.
FAILED: org.apache.solr.search.facet.TestCloudJSONFacetSKG.testRandom
Error Message:
init query failed: {main(q=(field_6_ss:29+OR+field_2_sdsS:48+OR+field_4_sds:18)&json.facet={+processEmpty:+true,+facet_1+:+{+type:terms,+field:field_4_ids,+limit:50,+overrequest:0,+sort:+'count+asc',+refine:+true,+domain:+{+query:+'*:*'+},+facet:{+processEmpty:+true,+facet_2+:+{+type:terms,+field:field_9_is,+limit:50,+overrequest:2116878686,+sort:+'skg+asc',+refine:+true,+domain:+{+query:+'*:*'+},+facet:{+processEmpty:+true,+facet_3+:+{+type:terms,+field:field_13_ids,+limit:50,+overrequest:0,+sort:+'skg+desc',+refine:+true,+domain:+{+query:+'*:*'+},+facet:{+processEmpty:+true,+skg+:+'relatedness($fore,$back)'}}+,skg+:+'relatedness($fore,$back)'}}+,skg+:+'relatedness($fore,$back)'}}+}),extra(rows=0&fore=(field_8_sdsS:43+OR+field_1_sds:21+OR+field_9_ss:41)&back=(field_12_ss:54+OR+field_5_sdsS:9+OR+field_0_ss:26+OR+field_0_ss:19+OR+field_3_ss:7+OR+field_13_sds:22+OR+field_1_sds:20))}: Error from server at https://127.0.0.1:42469/solr/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection: parsing error
Stack Trace:
java.lang.RuntimeException: init query failed: {main(q=(field_6_ss:29+OR+field_2_sdsS:48+OR+field_4_sds:18)&json.facet={+processEmpty:+true,+facet_1+:+{+type:terms,+field:field_4_ids,+limit:50,+overrequest:0,+sort:+'count+asc',+refine:+true,+domain:+{+query:+'*:*'+},+facet:{+processEmpty:+true,+facet_2+:+{+type:terms,+field:field_9_is,+limit:50,+overrequest:2116878686,+sort:+'skg+asc',+refine:+true,+domain:+{+query:+'*:*'+},+facet:{+processEmpty:+true,+facet_3+:+{+type:terms,+field:field_13_ids,+limit:50,+overrequest:0,+sort:+'skg+desc',+refine:+true,+domain:+{+query:+'*:*'+},+facet:{+processEmpty:+true,+skg+:+'relatedness($fore,$back)'}}+,skg+:+'relatedness($fore,$back)'}}+,skg+:+'relatedness($fore,$back)'}}+}),extra(rows=0&fore=(field_8_sdsS:43+OR+field_1_sds:21+OR+field_9_ss:41)&back=(field_12_ss:54+OR+field_5_sdsS:9+OR+field_0_ss:26+OR+field_0_ss:19+OR+field_3_ss:7+OR+field_13_sds:22+OR+field_1_sds:20))}: Error from server at https://127.0.0.1:42469/solr/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection: parsing error
at __randomizedtesting.SeedInfo.seed([9C1EBF3D9995E822:EE529A3228F55E51]:0)
at org.apache.solr.search.facet.TestCloudJSONFacetSKG.assertFacetSKGsAreCorrect(TestCloudJSONFacetSKG.java:337)
at org.apache.solr.search.facet.TestCloudJSONFacetSKG.testRandom(TestCloudJSONFacetSKG.java:274)
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:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:42469/solr/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection: parsing error
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:638)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:265)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:368)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:296)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1143)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:906)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:838)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:228)
at org.apache.solr.search.facet.TestCloudJSONFacetSKG.assertFacetSKGsAreCorrect(TestCloudJSONFacetSKG.java:331)
... 39 more
Caused by: org.apache.solr.common.SolrException: parsing error
at org.apache.solr.client.solrj.impl.BinaryResponseParser.processResponse(BinaryResponseParser.java:52)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:636)
... 49 more
Caused by: org.apache.http.MalformedChunkCodingException: CRLF expected at end of chunk
at org.apache.http.impl.io.ChunkedInputStream.getChunkSize(ChunkedInputStream.java:250)
at org.apache.http.impl.io.ChunkedInputStream.nextChunk(ChunkedInputStream.java:222)
at org.apache.http.impl.io.ChunkedInputStream.read(ChunkedInputStream.java:183)
at org.apache.http.conn.EofSensorInputStream.read(EofSensorInputStream.java:135)
at org.apache.solr.common.util.FastInputStream.readWrappedStream(FastInputStream.java:90)
at org.apache.solr.common.util.FastInputStream.refill(FastInputStream.java:99)
at org.apache.solr.common.util.FastInputStream.readUnsignedByte(FastInputStream.java:80)
at org.apache.solr.common.util.FastInputStream.readLong(FastInputStream.java:249)
at org.apache.solr.common.util.FastInputStream.readDouble(FastInputStream.java:266)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:322)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readOrderedMap(JavaBinCodec.java:227)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:301)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readOrderedMap(JavaBinCodec.java:227)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:301)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readArray(JavaBinCodec.java:799)
at org.apache.solr.common.util.JavaBinCodec.readArray(JavaBinCodec.java:793)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:299)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readOrderedMap(JavaBinCodec.java:227)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:301)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readOrderedMap(JavaBinCodec.java:227)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:301)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readArray(JavaBinCodec.java:799)
at org.apache.solr.common.util.JavaBinCodec.readArray(JavaBinCodec.java:793)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:299)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readOrderedMap(JavaBinCodec.java:227)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:301)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readOrderedMap(JavaBinCodec.java:227)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:301)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readArray(JavaBinCodec.java:799)
at org.apache.solr.common.util.JavaBinCodec.readArray(JavaBinCodec.java:793)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:299)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readOrderedMap(JavaBinCodec.java:227)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:301)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readOrderedMap(JavaBinCodec.java:227)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:301)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.readOrderedMap(JavaBinCodec.java:227)
at org.apache.solr.common.util.JavaBinCodec.readObject(JavaBinCodec.java:301)
at org.apache.solr.common.util.JavaBinCodec.readVal(JavaBinCodec.java:283)
at org.apache.solr.common.util.JavaBinCodec.unmarshal(JavaBinCodec.java:196)
at org.apache.solr.client.solrj.impl.BinaryResponseParser.processResponse(BinaryResponseParser.java:50)
... 50 more
FAILED: org.apache.solr.search.facet.TestCloudJSONFacetSKG.testBespoke
Error Message:
Captured an uncaught exception in thread: Thread[id=36072, name=SolrRrdBackendFactory-9181-thread-2, state=RUNNABLE, group=TGRP-TestCloudJSONFacetSKG]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=36072, name=SolrRrdBackendFactory-9181-thread-2, state=RUNNABLE, group=TGRP-TestCloudJSONFacetSKG]
at __randomizedtesting.SeedInfo.seed([9C1EBF3D9995E822:97C53B0451152FD5]:0)
Caused by: java.lang.OutOfMemoryError: Java heap space
Build Log:
[...truncated 16276 lines...]
[junit4] Suite: org.apache.solr.search.facet.TestCloudJSONFacetSKG
[junit4] 2> 1222773 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1222773 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1222774 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestCloudJSONFacetSKG_9C1EBF3D9995E822-001/data-dir-175-001
[junit4] 2> 1222774 WARN (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 1222774 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 1222775 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) 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> 1222776 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestCloudJSONFacetSKG_9C1EBF3D9995E822-001/tempDir-001
[junit4] 2> 1222776 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1222791 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1222791 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1222891 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:38259
[junit4] 2> 1222891 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38259
[junit4] 2> 1222891 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38259
[junit4] 2> 1222891 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38259
[junit4] 2> 1222936 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1222972 INFO (zkConnectionManagerCallback-10908-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1222972 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1223028 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1223069 INFO (zkConnectionManagerCallback-10910-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1223069 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1223099 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1223140 INFO (zkConnectionManagerCallback-10912-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1223140 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1223392 WARN (jetty-launcher-10913-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1223392 WARN (jetty-launcher-10913-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 1223392 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1223392 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1223392 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1223392 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 1223393 INFO (jetty-launcher-10913-thread-1) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+16-681
[junit4] 2> 1223393 INFO (jetty-launcher-10913-thread-2) [ ] o.e.j.s.Server jetty-9.4.24.v20191120; built: 2019-11-20T21:37:49.771Z; git: 363d5f2df3a8a28de40604320230664b9c793c16; jvm 15-ea+16-681
[junit4] 2> 1223537 INFO (jetty-launcher-10913-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1223537 INFO (jetty-launcher-10913-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1223537 INFO (jetty-launcher-10913-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1223548 INFO (jetty-launcher-10913-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@31fc3922{/solr,null,AVAILABLE}
[junit4] 2> 1223553 INFO (jetty-launcher-10913-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1223553 INFO (jetty-launcher-10913-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1223554 INFO (jetty-launcher-10913-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1223559 INFO (jetty-launcher-10913-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5aef54d5{/solr,null,AVAILABLE}
[junit4] 2> 1223566 INFO (jetty-launcher-10913-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5658ac81{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:42631}
[junit4] 2> 1223566 INFO (jetty-launcher-10913-thread-2) [ ] o.e.j.s.Server Started @1223868ms
[junit4] 2> 1223566 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42631}
[junit4] 2> 1223566 ERROR (jetty-launcher-10913-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1223566 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1223566 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1223566 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1223566 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 1223566 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-02T17:38:51.723843Z
[junit4] 2> 1223576 INFO (jetty-launcher-10913-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@60d7f019{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:42469}
[junit4] 2> 1223576 INFO (jetty-launcher-10913-thread-1) [ ] o.e.j.s.Server Started @1223877ms
[junit4] 2> 1223576 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=42469}
[junit4] 2> 1223576 ERROR (jetty-launcher-10913-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1223576 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1223576 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.6.0
[junit4] 2> 1223576 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1223576 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 1223576 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-04-02T17:38:51.733834Z
[junit4] 2> 1223581 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1223611 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1223671 INFO (zkConnectionManagerCallback-10915-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1223671 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1223687 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1223695 INFO (zkConnectionManagerCallback-10917-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1223695 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1223732 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1223945 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1223955 WARN (jetty-launcher-10913-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4a23408a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1223955 WARN (jetty-launcher-10913-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4a23408a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1223973 WARN (jetty-launcher-10913-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@34661842[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1223973 WARN (jetty-launcher-10913-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@34661842[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1223975 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38259/solr
[junit4] 2> 1223996 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1224005 INFO (zkConnectionManagerCallback-10925-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1224015 INFO (jetty-launcher-10913-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1224135 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1224139 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1224157 INFO (zkConnectionManagerCallback-10927-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1224157 WARN (jetty-launcher-10913-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3eb60367[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1224157 WARN (jetty-launcher-10913-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3eb60367[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1224158 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1224199 WARN (jetty-launcher-10913-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@184df7c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1224199 WARN (jetty-launcher-10913-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@184df7c9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1224200 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38259/solr
[junit4] 2> 1224222 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1224223 INFO (zkConnectionManagerCallback-10935-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1224223 INFO (jetty-launcher-10913-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1224262 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:42631_solr
[junit4] 2> 1224280 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.c.Overseer Overseer (id=72154199428235270-127.0.0.1:42631_solr-n_0000000000) starting
[junit4] 2> 1224318 INFO (OverseerStateUpdate-72154199428235270-127.0.0.1:42631_solr-n_0000000000) [n:127.0.0.1:42631_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:42631_solr
[junit4] 2> 1224326 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42631_solr
[junit4] 2> 1224345 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1224346 WARN (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_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> 1224367 INFO (zkCallback-10926-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1224375 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1224403 INFO (zkConnectionManagerCallback-10938-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1224403 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1224421 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1224435 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1224440 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:42469_solr as DOWN
[junit4] 2> 1224443 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1224443 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:42469_solr
[junit4] 2> 1224446 INFO (zkCallback-10926-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1224447 INFO (zkCallback-10937-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1224449 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1224449 WARN (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_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> 1224450 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42631.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15716f59
[junit4] 2> 1224465 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1224466 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42631.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15716f59
[junit4] 2> 1224467 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42631.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15716f59
[junit4] 2> 1224468 INFO (jetty-launcher-10913-thread-2) [n:127.0.0.1:42631_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestCloudJSONFacetSKG_9C1EBF3D9995E822-001/tempDir-001/node2/.
[junit4] 2> 1224478 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42469.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15716f59
[junit4] 2> 1224513 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42469.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15716f59
[junit4] 2> 1224518 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42469.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15716f59
[junit4] 2> 1224519 INFO (jetty-launcher-10913-thread-1) [n:127.0.0.1:42469_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestCloudJSONFacetSKG_9C1EBF3D9995E822-001/tempDir-001/node1/.
[junit4] 2> 1224572 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1224573 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1224581 INFO (zkConnectionManagerCallback-10948-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1224581 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1224582 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1224583 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38259/solr ready
[junit4] 2> 1225505 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params property.schema=schema_latest.xml&property.config=solrconfig-tlog.xml&collection.configName=org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set&name=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 1225521 INFO (OverseerThreadFactory-9176-thread-1-processing-n:127.0.0.1:42631_solr) [n:127.0.0.1:42631_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection
[junit4] 2> 1225643 INFO (OverseerStateUpdate-72154199428235270-127.0.0.1:42631_solr-n_0000000000) [n:127.0.0.1:42631_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42631/solr",
[junit4] 2> "node_name":"127.0.0.1:42631_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1225645 INFO (OverseerStateUpdate-72154199428235270-127.0.0.1:42631_solr-n_0000000000) [n:127.0.0.1:42631_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:42469/solr",
[junit4] 2> "node_name":"127.0.0.1:42469_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1225887 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set&newCollection=true&collection=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection&version=2&replicaType=NRT&property.schema=schema_latest.xml&property.config=solrconfig-tlog.xml&coreNodeName=core_node4&name=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3&action=CREATE&numShards=2&shard=shard2&wt=javabin
[junit4] 2> 1225891 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set&newCollection=true&collection=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection&version=2&replicaType=NRT&property.schema=schema_latest.xml&property.config=solrconfig-tlog.xml&coreNodeName=core_node2&name=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin
[junit4] 2> 1225892 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1225895 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1225911 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 1225929 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 1225937 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.s.IndexSchema Schema name=example
[junit4] 2> 1226367 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 1226369 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema example/1.6 with uniqueid field id
[junit4] 2> 1226383 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3' using configuration from configset org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set, trusted=true
[junit4] 2> 1226383 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1' using configuration from configset org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set, trusted=true
[junit4] 2> 1226384 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42469.solr.core.org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection.shard2.replica_n3' (registry 'solr.core.org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection.shard2.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15716f59
[junit4] 2> 1226385 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.SolrCore [[org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestCloudJSONFacetSKG_9C1EBF3D9995E822-001/tempDir-001/node1/./org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestCloudJSONFacetSKG_9C1EBF3D9995E822-001/tempDir-001/node1/./org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3/data/]
[junit4] 2> 1226392 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1226392 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_42631.solr.core.org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection.shard1.replica_n1' (registry 'solr.core.org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15716f59
[junit4] 2> 1226392 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestCloudJSONFacetSKG_9C1EBF3D9995E822-001/tempDir-001/node2/./org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestCloudJSONFacetSKG_9C1EBF3D9995E822-001/tempDir-001/node2/./org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1/data/]
[junit4] 2> 1226393 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=47, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 1226397 WARN (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 1226403 WARN (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 1226506 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1226506 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1226506 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1226506 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1226508 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1226508 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1226508 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 1226508 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1226509 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=25.732421875, floorSegmentMB=0.9072265625, forceMergeDeletesPctAllowed=8.973480522193597, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6134737619948372, deletesPctAllowed=32.68066972403551
[junit4] 2> 1226509 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=16, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=25.732421875, floorSegmentMB=0.9072265625, forceMergeDeletesPctAllowed=8.973480522193597, segmentsPerTier=14.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.6134737619948372, deletesPctAllowed=32.68066972403551
[junit4] 2> 1226509 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@614a5b29[org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] main]
[junit4] 2> 1226509 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@57133542[org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] main]
[junit4] 2> 1226510 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set
[junit4] 2> 1226511 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set
[junit4] 2> 1226511 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1226512 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set
[junit4] 2> 1226512 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1662883342234681344
[junit4] 2> 1226514 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set
[junit4] 2> 1226514 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 1226514 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1662883342236778496
[junit4] 2> 1226521 INFO (searcherExecutor-9186-thread-1-processing-n:127.0.0.1:42631_solr x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] Registered new searcher Searcher@57133542[org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1226527 INFO (searcherExecutor-9185-thread-1-processing-n:127.0.0.1:42469_solr x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.SolrCore [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] Registered new searcher Searcher@614a5b29[org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 1226531 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1226531 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/leaders/shard1
[junit4] 2> 1226538 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 1226538 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1226539 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1226539 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:42631/solr/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1/
[junit4] 2> 1226539 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/leaders/shard2
[junit4] 2> 1226539 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1226539 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:42631/solr/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 1226539 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/leaders/shard1/leader after winning as /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/leader_elect/shard1/election/72154199428235270-core_node2-n_0000000000
[junit4] 2> 1226540 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:42631/solr/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1/ shard1
[junit4] 2> 1226541 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1226541 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1226541 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:42469/solr/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3/
[junit4] 2> 1226541 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1226541 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.SyncStrategy https://127.0.0.1:42469/solr/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3/ has no replicas
[junit4] 2> 1226541 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/leaders/shard2/leader after winning as /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/leader_elect/shard2/election/72154199428235272-core_node4-n_0000000000
[junit4] 2> 1226542 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:42469/solr/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3/ shard2
[junit4] 2> 1226644 INFO (zkCallback-10937-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1226645 INFO (zkCallback-10926-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1226645 INFO (zkCallback-10926-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1226646 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1226647 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1226648 INFO (zkCallback-10937-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1226649 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set&newCollection=true&collection=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection&version=2&replicaType=NRT&property.schema=schema_latest.xml&property.config=solrconfig-tlog.xml&coreNodeName=core_node2&name=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1&action=CREATE&numShards=2&shard=shard1&wt=javabin} status=0 QTime=757
[junit4] 2> 1226649 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set&newCollection=true&collection=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection&version=2&replicaType=NRT&property.schema=schema_latest.xml&property.config=solrconfig-tlog.xml&coreNodeName=core_node4&name=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3&action=CREATE&numShards=2&shard=shard2&wt=javabin} status=0 QTime=762
[junit4] 2> 1226650 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 1226768 INFO (zkCallback-10926-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1226768 INFO (zkCallback-10926-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1226768 INFO (zkCallback-10937-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1226768 INFO (zkCallback-10937-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1226769 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={property.schema=schema_latest.xml&property.config=solrconfig-tlog.xml&collection.configName=org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set&name=org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=1263
[junit4] 2> 1226769 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection failOnTimeout:true timeout (sec):330
[junit4] 2> 1226771 INFO (zkCallback-10926-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1226787 INFO (zkCallback-10937-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json] for collection [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection] has occurred - updating... (live nodes size: [2])
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:active live:true
[junit4] 1> no one is recoverying
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:active live:true
[junit4] 1> no one is recoverying
[junit4] 1> replica:core_node2 rstate:active live:true
[junit4] 1> replica:core_node4 rstate:active live:true
[junit4] 1> no one is recoverying
[junit4] 2> 1226814 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection
[junit4] 2> 1226870 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/terms/shard1 to Terms{values={core_node2=1}, version=1}
[junit4] 2> 1226870 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[0 (1662883342606925824)]} 0 3
[junit4] 2> 1226872 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[1 (1662883342612168704)]} 0 0
[junit4] 2> 1226876 INFO (qtp791478711-36021) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/terms/shard2 to Terms{values={core_node4=1}, version=1}
[junit4] 2> 1226876 INFO (qtp791478711-36021) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[2 (1662883342615314432)]} 0 2
[junit4] 2> 1226877 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[3 (1662883342617411584)]} 0 0
[junit4] 2> 1226880 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[4 (1662883342619508736)]} 0 0
[junit4] 2> 1226881 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[5 (1662883342621605888)]} 0 0
[junit4] 2> 1226885 INFO (qtp791478711-36021) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[6 (1662883342625800192)]} 0 0
[junit4] 2> 1226886 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7 (1662883342626848768)]} 0 0
[junit4] 2> 1226887 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[7 (1662883342627897344)]} 0 0
[junit4] 2> 1226888 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[8 (1662883342628945920)]} 0 0
[junit4] 2> 1226889 INFO (qtp791478711-36021) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9 (1662883342629994496)]} 0 0
[junit4] 2> 1226890 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[9 (1662883342631043072)]} 0 0
[junit4] 2> 1226891 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[10 (1662883342631043072)]} 0 0
[junit4] 2> 1226891 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[11 (1662883342632091648)]} 0 0
[junit4] 2> 1226892 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[12 (1662883342633140224)]} 0 0
[junit4] 2> 1226893 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[13 (1662883342634188800)]} 0 0
[junit4] 2> 1226893 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[14 (1662883342634188801)]} 0 0
[junit4] 2> 1226894 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[15 (1662883342635237376)]} 0 0
[junit4] 2> 1226895 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[16 (1662883342636285952)]} 0 0
[junit4] 2> 1226896 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[17 (1662883342636285952)]} 0 0
[junit4] 2> 1226896 INFO (qtp791478711-36021) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[18 (1662883342637334528)]} 0 0
[junit4] 2> 1226901 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[19 (1662883342641528832)]} 0 0
[junit4] 2> 1226904 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[20 (1662883342644674560)]} 0 0
[junit4] 2> 1226908 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[21 (1662883342649917440)]} 0 0
[junit4] 2> 1226909 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[22 (1662883342650966016)]} 0 0
[junit4] 2> 1226910 INFO (qtp791478711-36021) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[23 (1662883342652014592)]} 0 0
[junit4] 2> 1226911 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[24 (1662883342653063168)]} 0 0
[junit4] 2> 1226912 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25 (1662883342654111744)]} 0 0
[junit4] 2> 1226912 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[25 (1662883342654111745)]} 0 0
[junit4] 2> 1226913 INFO (qtp791478711-36021) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[26 (1662883342655160320)]} 0 0
[junit4] 2> 1226914 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[27 (1662883342656208896)]} 0 0
[junit4] 2> 1226915 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[28 (1662883342657257472)]} 0 0
[junit4] 2> 1226916 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[29 (1662883342658306048)]} 0 0
[junit4] 2> 1226917 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[30 (1662883342659354624)]} 0 0
[junit4] 2> 1226918 INFO (qtp791478711-36021) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[31 (1662883342660403200)]} 0 0
[junit4] 2> 1226919 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[32 (1662883342661451776)]} 0 0
[junit4] 2> 1226920 INFO (qtp791478711-36013) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[33 (1662883342661451776)]} 0 0
[junit4] 2> 1226920 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[34 (1662883342662500352)]} 0 0
[junit4] 2> 1226921 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[35 (1662883342663548928)]} 0 0
[junit4] 2> 1226922 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[36 (1662883342664597504)]} 0 0
[junit4] 2> 1226924 INFO (qtp791478711-36011) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[37 (1662883342665646080)]} 0 0
[junit4] 2> 1226925 INFO (qtp430070782-36020) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[38 (1662883342666694656)]} 0 0
[junit4] 2> 1226926 INFO (qtp791478711-36021) [n:127.0.0.1:42469_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard2 r:core_node4 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3 ] o.a.s.u.p.LogUpdateProcessorFactory [org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard2_replica_n3] webapp=/solr path=/update params={wt=javabin&version=2}{add=[39 (1662883342667743232)]} 0 0
[junit4] 2> 1226926 INFO (qtp430070782-36018) [n:127.0.0.1:42631_solr c:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection s:shard1 r:core_node2 x:org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [org.ap
[...truncated too long message...]
trics.MetricRegistry@1dfb2f0b
[junit4] 2> 1292178 INFO (jetty-closer-10951-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 1292187 INFO (closeThreadPool-10958-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72154199428235270-127.0.0.1:42631_solr-n_0000000000) closing
[junit4] 2> 1292200 INFO (zkCallback-10937-thread-10) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 1292299 INFO (jetty-closer-10951-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72154199428235270-127.0.0.1:42631_solr-n_0000000000) closing
[junit4] 2> 1292302 INFO (jetty-closer-10951-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5658ac81{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 1292303 INFO (jetty-closer-10951-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@31fc3922{/solr,null,UNAVAILABLE}
[junit4] 2> 1292303 INFO (jetty-closer-10951-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1292402 INFO (jetty-closer-10951-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@60d7f019{ssl,[ssl, alpn, http/1.1, h2]}{127.0.0.1:0}
[junit4] 2> 1292402 INFO (jetty-closer-10951-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@5aef54d5{/solr,null,UNAVAILABLE}
[junit4] 2> 1292402 INFO (jetty-closer-10951-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 1292404 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 1292616 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/terms/shard1
[junit4] 2> 3 /solr/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/terms/shard2
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/collectionprops.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/configs/org.apache.solr.search.facet.TestCloudJSONFacetSKG_config-set
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 31 /solr/collections/org.apache.solr.search.facet.TestCloudJSONFacetSKG_collection/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 18 /solr/overseer/queue
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 1292619 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:38259
[junit4] 2> 1292619 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38259
[junit4] 2> 1292619 INFO (SUITE-TestCloudJSONFacetSKG-seed#[9C1EBF3D9995E822]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38259
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.search.facet.TestCloudJSONFacetSKG_9C1EBF3D9995E822-001
[junit4] 2> Apr 02, 2020 5:40:00 PM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene84, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@5593d8e0), locale=guz-KE, timezone=America/Sitka
[junit4] 2> NOTE: Linux 5.3.0-42-generic amd64/Oracle Corporation 15-ea (64-bit)/cpus=16,threads=1,free=255852544,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [TestAtomicUpdateErrorCases, TestEmbeddedSolrServerAdminHandler, TestJsonFacetRefinement, TestSnapshotCloudManager, TestSubQueryTransformerCrossCore, CollectionsAPISolrJTest, ScriptEngineTest, TestAnalyzedSuggestions, TestUniqueKeyFieldResource, TlogReplayBufferedWhileIndexingTest, TestFieldTypeResource, TestStandardQParsers, TestInitQParser, SolrLogAuditLoggerPluginTest, AddReplicaTest, UniqFieldsUpdateProcessorFactoryTest, QueryResultKeyTest, UpdateParamsTest, TestCollectionsAPIViaSolrCloudCluster, HdfsChaosMonkeyNothingIsSafeTest, BlockJoinFacetDistribTest, MaxSizeAutoCommitTest, DistributedSuggestComponentTest, AddBlockUpdateTest, QueryElevationComponentTest, MetricUtilsTest, AutoAddReplicasPlanActionTest, JavabinLoaderTest, TestCloudSchemaless, TestCursorMarkWithoutUniqueKey, TestSimNodeAddedTrigger, MinimalSchemaTest, TestLMDirichletSimilarityFactory, JWTAuthPluginIntegrationTest, ForceLeaderTest, TestCustomSort, CheckHdfsIndexTest, TestCloudPseudoReturnFields, DistributedExpandComponentTest, TestSearchPerf, TestRetrieveFieldsOptimizer, NoCacheHeaderTest, CdcrRequestHandlerTest, WordBreakSolrSpellCheckerTest, TestScoreJoinQPNoScore, TestDownShardTolerantSearch, XmlUpdateRequestHandlerTest, ScheduledTriggerIntegrationTest, CoreAdminOperationTest, SystemInfoHandlerTest, TestQueryingOnDownCollection, TestSolrConfigHandlerCloud, TestSortableTextField, TestSolrCloudWithSecureImpersonation, PackageManagerCLITest, IndexSchemaTest, SolrCLIZkUtilsTest, CustomCollectionTest, TestBlobHandler, SSLMigrationTest, CollectionReloadTest, TestReRankQParserPlugin, RootFieldTest, TestFilteredDocIdSet, LeaderTragicEventTest, TestCopyFieldCollectionResource, TestDocTermOrds, TestManagedSchemaThreadSafety, TestClusterStateMutator, BadComponentTest, TestLRUStatsCache, TestSimDistribStateManager, TestMissingGroups, ConfigureRecoveryStrategyTest, TestImpersonationWithHadoopAuth, TestCharFilters, TestLuceneMatchVersion, BasicZkTest, ChaosMonkeyNothingIsSafeWithPullReplicasTest, ClusterStateMockUtilTest, ClusterStateTest, CreateRoutedAliasTest, DeleteNodeTest, DeleteShardTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, DistributedQueueTest, DistributedVersionInfoTest, HttpPartitionWithTlogReplicasTest, MoveReplicaHDFSFailoverTest, MoveReplicaHDFSTest, MoveReplicaTest, OverseerRolesTest, RemoteQueryErrorTest, ReplaceNodeNoTargetTest, ReplaceNodeTest, SharedFSAutoReplicaFailoverTest, SystemCollectionCompatTest, TestAuthenticationFramework, TestCloudInspectUtil, TestCloudPhrasesIdentificationComponent, TestCloudSearcherWarming, TestShortCircuitedRequests, TestUtilizeNode, ZkControllerTest, ZkFailoverTest, ExecutePlanActionTest, NodeAddedTriggerIntegrationTest, NodeLostTriggerTest, TestSimExtremeIndexing, TestSimGenericDistributedQueue, TestSimScenario, CdcrBootstrapTest, TestImplicitCoreProperties, TestNRTOpen, TestQuerySenderNoQuery, TestSolrDeletionPolicy1, TestSolrDeletionPolicy2, TestSolrXml, TestSolrCloudSnapshots, TestSystemCollAutoCreate, TestConfigsApi, TestCoreAdminApis, CustomTermsComponentTest, DistributedFacetPivotSmallTest, DistributedQueryComponentCustomSortTest, SearchHandlerTest, TestDistributedStatsComponentCardinality, RegexBytesRefFilterTest, SimpleFacetsTest, TestUnInvertedFieldException, TestWriterPerf, TestMacroExpander, JSONWriterTest, TestBinaryResponseWriter, TestCustomDocTransformer, TestGraphMLResponseWriter, TestManagedResourceStorage, CurrencyFieldTypeTest, PolyFieldTest, PrimitiveFieldTypeTest, SynonymTokenizerTest, TestHalfAndHalfDocValues, TestManagedSchema, TestHashQParserPlugin, TestQueryTypes, TestQueryUtils, TestSolrCachePerf, TestSolrCoreParser, TestSolrFieldCacheBean, TestSolrQueryParser, TestSurroundQueryParser, TestTermsQParserPlugin, DistributedFacetSimpleRefinementLongTailTest, TestCloudJSONFacetSKG]
[junit4] Completed [846/904 (1!)] on J1 in 70.84s, 2 tests, 2 errors <<< FAILURES!
[...truncated 214 lines...]
[junit4] JVM J1: stdout was not empty, see: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/temp/junit4-J1-20200402_171827_8302006332541510682580.sysout
[junit4] >>> JVM J1 emitted unexpected output (verbatim) ----
[junit4] java.lang.OutOfMemoryError: Java heap space
[junit4] Dumping heap to /home/jenkins/workspace/Lucene-Solr-8.x-Linux/heapdumps/java_pid22768.hprof ...
[junit4] Heap dump file created [471716250 bytes in 2.566 secs]
[junit4] <<< JVM J1: EOF ----
[...truncated 9599 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/build.xml:634: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/build.xml:586: Some of the tests produced a heap dump, but did not fail. Maybe a suppressed OutOfMemoryError? Dumps created:
* java_pid22768.hprof
Total time: 39 minutes 9 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
[WARNINGS] Skipping publisher since build result is FAILURE
Recording test results
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2