You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2019/07/13 21:50:05 UTC
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-12.0.1) - Build #
8052 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8052/
Java: 64bit/jdk-12.0.1 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream
Error Message:
expected:<0.0> but was:<0.9998245650830389>
Stack Trace:
java.lang.AssertionError: expected:<0.0> but was:<0.9998245650830389>
at __randomizedtesting.SeedInfo.seed([C3AAD41545BDA373:66E24E2D7CE5BAE7]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:553)
at org.junit.Assert.assertEquals(Assert.java:683)
at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testClassifyStream(StreamDecoratorTest.java:3680)
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:567)
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:835)
Build Log:
[...truncated 16316 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4] 2> 17026 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\init-core-data-001
[junit4] 2> 17198 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 17260 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.e.j.u.log Logging initialized @12811ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 17276 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None")
[junit4] 2> 17354 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001
[junit4] 2> 17365 WARN (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.ZkTestServer TEST_HOME() does not exist - solrj test?
[junit4] 2> 17413 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 17420 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 17420 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 17561 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:56474
[junit4] 2> 17571 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:56474
[junit4] 2> 17579 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 56474
[junit4] 2> 17736 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 17791 INFO (zkConnectionManagerCallback-3-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 17791 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 17821 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 17827 INFO (zkConnectionManagerCallback-5-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 17827 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 17831 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 17837 INFO (zkConnectionManagerCallback-7-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 17837 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 18326 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 18326 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 18326 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 18327 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 18469 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 18469 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 18469 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> 18469 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> 18469 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 18469 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> 18474 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 18474 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> 18483 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 18483 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 18483 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 18483 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 18496 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 18496 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 18497 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 18497 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 18497 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 18497 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 18499 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 18499 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 18502 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 18502 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 18502 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 18504 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 18528 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@67e04832{/solr,null,AVAILABLE}
[junit4] 2> 18528 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ae6b9d0{/solr,null,AVAILABLE}
[junit4] 2> 18528 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@53b579b2{/solr,null,AVAILABLE}
[junit4] 2> 18528 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3f913e57{/solr,null,AVAILABLE}
[junit4] 2> 18546 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5840f8de{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:56490}
[junit4] 2> 18546 INFO (jetty-launcher-8-thread-4) [ ] o.e.j.s.Server Started @13734ms
[junit4] 2> 18546 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56490}
[junit4] 2> 18546 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1f808b38{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:56487}
[junit4] 2> 18546 INFO (jetty-launcher-8-thread-1) [ ] o.e.j.s.Server Started @13734ms
[junit4] 2> 18546 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56487}
[junit4] 2> 18548 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@68b0a7f1{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:56489}
[junit4] 2> 18548 INFO (jetty-launcher-8-thread-3) [ ] o.e.j.s.Server Started @13735ms
[junit4] 2> 18548 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56489}
[junit4] 2> 18548 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@48550e0e{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:56488}
[junit4] 2> 18548 INFO (jetty-launcher-8-thread-2) [ ] o.e.j.s.Server Started @13735ms
[junit4] 2> 18548 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=56488}
[junit4] 2> 18775 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> 18775 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> 18775 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> 18775 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 18775 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 18775 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> 18775 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 18775 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 18775 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 18775 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 18775 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 18775 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-13T21:06:59.844412Z
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-13T21:06:59.844412Z
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-13T21:06:59.844412Z
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 18779 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-13T21:06:59.844412Z
[junit4] 2> 18871 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.SolrResourceLoader solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 18873 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 18877 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 18879 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 18879 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 18894 INFO (zkConnectionManagerCallback-12-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 18894 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 18905 INFO (zkConnectionManagerCallback-14-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 18905 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 18906 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 18906 INFO (zkConnectionManagerCallback-10-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 18911 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 18912 INFO (zkConnectionManagerCallback-16-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 18912 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 18916 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 18918 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 18918 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 20731 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 20732 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 20857 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 21075 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3b17af6f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21075 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3b17af6f[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21075 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@40c95bc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21075 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@40c95bc[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21075 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2f06d187[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21075 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2f06d187[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21306 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 21315 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3d7896d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21315 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3d7896d5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21357 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7b816097[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21357 WARN (jetty-launcher-8-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7b816097[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21358 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@60408302[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21360 WARN (jetty-launcher-8-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@60408302[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21361 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1ebef290[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21361 WARN (jetty-launcher-8-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1ebef290[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21364 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@31e08e7a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21364 WARN (jetty-launcher-8-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@31e08e7a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 21421 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56474/solr
[junit4] 2> 21423 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56474/solr
[junit4] 2> 21423 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56474/solr
[junit4] 2> 21423 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:56474/solr
[junit4] 2> 21501 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 21502 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 21502 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 21504 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 21522 INFO (zkConnectionManagerCallback-44-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 21522 INFO (jetty-launcher-8-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 21522 INFO (zkConnectionManagerCallback-39-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 21523 INFO (jetty-launcher-8-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 21524 INFO (zkConnectionManagerCallback-40-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 21524 INFO (jetty-launcher-8-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 21526 INFO (zkConnectionManagerCallback-42-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 21526 INFO (jetty-launcher-8-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 21695 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 21698 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 21702 INFO (zkConnectionManagerCallback-48-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 21703 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 21704 INFO (zkConnectionManagerCallback-47-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 21705 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 21790 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 21800 INFO (zkConnectionManagerCallback-54-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 21800 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 21845 WARN (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 21847 WARN (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 21848 WARN (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 21917 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 21921 INFO (zkConnectionManagerCallback-58-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 21921 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 21937 WARN (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.c.ZkController The _default configset could not be uploaded. Please provide 'solr.default.confdir' parameter that points to a configset intended to be the default. Current 'solr.default.confdir' value: null
[junit4] 2> 21985 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:56487_solr
[junit4] 2> 21994 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.Overseer Overseer (id=72075598312701964-127.0.0.1:56487_solr-n_0000000000) starting
[junit4] 2> 21994 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56490_solr
[junit4] 2> 21998 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56489_solr
[junit4] 2> 21999 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56488_solr
[junit4] 2> 22032 INFO (zkCallback-46-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 22041 INFO (zkCallback-45-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 22050 INFO (zkCallback-57-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 22050 INFO (zkCallback-53-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 22109 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 22110 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 22114 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 22138 INFO (zkConnectionManagerCallback-75-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 22138 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 22143 INFO (zkConnectionManagerCallback-73-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 22143 INFO (zkConnectionManagerCallback-71-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 22143 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 22143 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 22150 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 22150 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 22151 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 22155 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56474/solr ready
[junit4] 2> 22156 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56474/solr ready
[junit4] 2> 22156 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56474/solr ready
[junit4] 2> 22169 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 22177 INFO (zkConnectionManagerCallback-80-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 22177 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 22181 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 22186 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56474/solr ready
[junit4] 2> 22534 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:56487_solr as DOWN
[junit4] 2> 22540 INFO (OverseerStateUpdate-72075598312701964-127.0.0.1:56487_solr-n_0000000000) [n:127.0.0.1:56487_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:56487_solr
[junit4] 2> 22557 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 22577 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56487_solr
[junit4] 2> 22592 INFO (zkCallback-46-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 22592 INFO (zkCallback-53-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 22592 INFO (zkCallback-45-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 22592 INFO (zkCallback-57-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 22679 INFO (zkCallback-70-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 22704 INFO (zkCallback-72-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 22704 INFO (zkCallback-74-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 22706 INFO (zkCallback-79-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 23047 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 23049 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 23049 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 23054 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 23595 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56487.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23596 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56487.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23597 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56487.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23602 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56490.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23607 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56489.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23609 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56488.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23632 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56488.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23632 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56488.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23632 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56490.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23633 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56490.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23634 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56489.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23634 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56489.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 23827 INFO (jetty-launcher-8-thread-2) [n:127.0.0.1:56488_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node1\.
[junit4] 2> 23827 INFO (jetty-launcher-8-thread-3) [n:127.0.0.1:56489_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node3\.
[junit4] 2> 23827 INFO (jetty-launcher-8-thread-1) [n:127.0.0.1:56487_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node2\.
[junit4] 2> 23827 INFO (jetty-launcher-8-thread-4) [n:127.0.0.1:56490_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node4\.
[junit4] 2> 24931 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 24937 INFO (zkConnectionManagerCallback-89-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 24937 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 24940 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 24943 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:56474/solr ready
[junit4] 2> 25502 INFO (qtp2122114411-99) [n:127.0.0.1:56489_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> 25573 INFO (OverseerThreadFactory-24-thread-1-processing-n:127.0.0.1:56487_solr) [n:127.0.0.1:56487_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 25817 INFO (OverseerStateUpdate-72075598312701964-127.0.0.1:56487_solr-n_0000000000) [n:127.0.0.1:56487_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:56487/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 25830 INFO (OverseerStateUpdate-72075598312701964-127.0.0.1:56487_solr-n_0000000000) [n:127.0.0.1:56487_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:56490/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 26115 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 26115 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 26119 INFO (qtp38569528-111) [n:127.0.0.1:56490_solr x:collection1_shard2_replica_n2 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 27500 INFO (qtp743374335-94) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 27500 INFO (qtp38569528-111) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 27759 INFO (qtp743374335-94) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 27759 INFO (qtp38569528-111) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 28530 WARN (qtp743374335-94) [n:127.0.0.1:56487_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.GeoHashField]. Please consult documentation how to replace it accordingly.
[junit4] 2> 28530 WARN (qtp38569528-111) [n:127.0.0.1:56490_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> 28549 WARN (qtp743374335-94) [n:127.0.0.1:56487_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.LatLonType]. Please consult documentation how to replace it accordingly.
[junit4] 2> 28550 WARN (qtp38569528-111) [n:127.0.0.1:56490_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> 28651 WARN (qtp38569528-111) [n:127.0.0.1:56490_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> 28651 WARN (qtp743374335-94) [n:127.0.0.1:56487_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> 28652 WARN (qtp38569528-111) [n:127.0.0.1:56490_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> 28652 WARN (qtp743374335-94) [n:127.0.0.1:56487_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> 28658 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 28658 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 28719 INFO (qtp743374335-94) [n:127.0.0.1:56487_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 collection collection1, trusted=true
[junit4] 2> 28719 INFO (qtp38569528-111) [n:127.0.0.1:56490_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 collection collection1, trusted=true
[junit4] 2> 28742 INFO (qtp743374335-94) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56487.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 28742 INFO (qtp38569528-111) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56490.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 28813 INFO (qtp743374335-94) [n:127.0.0.1:56487_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 [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node2\collection1_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node2\.\collection1_shard1_replica_n1\data\]
[junit4] 2> 28814 INFO (qtp38569528-111) [n:127.0.0.1:56490_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 [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node4\collection1_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node4\.\collection1_shard2_replica_n2\data\]
[junit4] 2> 33091 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33091 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33091 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33091 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33154 INFO (qtp743374335-94) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 33154 INFO (qtp743374335-94) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 33154 INFO (qtp38569528-111) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 33154 INFO (qtp38569528-111) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 33286 INFO (qtp38569528-111) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@533f2897[collection1_shard2_replica_n2] main]
[junit4] 2> 33286 INFO (qtp743374335-94) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@263759a4[collection1_shard1_replica_n1] main]
[junit4] 2> 33415 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33416 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33481 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33481 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33616 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33617 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33672 INFO (qtp38569528-111) [n:127.0.0.1:56490_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 1638978850376384512
[junit4] 2> 33676 INFO (qtp743374335-94) [n:127.0.0.1:56487_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 1638978850380578816
[junit4] 2> 33703 INFO (searcherExecutor-41-thread-1-processing-n:127.0.0.1:56487_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:56487_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@263759a4[collection1_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 33703 INFO (searcherExecutor-42-thread-1-processing-n:127.0.0.1:56490_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:56490_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@533f2897[collection1_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 33763 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33763 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33782 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33782 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33822 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33823 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33824 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33824 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33847 INFO (qtp743374335-94) [n:127.0.0.1:56487_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:56487/solr/collection1_shard1_replica_n1/
[junit4] 2> 33849 INFO (qtp38569528-111) [n:127.0.0.1:56490_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:56490/solr/collection1_shard2_replica_n2/
[junit4] 2> 33866 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 33866 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 33868 INFO (qtp743374335-94) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:56487/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 33871 INFO (qtp743374335-94) [n:127.0.0.1:56487_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/72075598312701964-core_node3-n_0000000000
[junit4] 2> 33873 INFO (qtp38569528-111) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:56490/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 33875 INFO (qtp38569528-111) [n:127.0.0.1:56490_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/72075598312701965-core_node4-n_0000000000
[junit4] 2> 33886 INFO (qtp743374335-94) [n:127.0.0.1:56487_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:56487/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 33886 INFO (qtp38569528-111) [n:127.0.0.1:56490_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:56490/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 33922 INFO (qtp743374335-94) [n:127.0.0.1:56487_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> 34007 INFO (qtp743374335-94) [n:127.0.0.1:56487_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=6376
[junit4] 2> 34107 INFO (zkCallback-46-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> 34107 INFO (zkCallback-46-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> 34107 INFO (zkCallback-53-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> 34122 INFO (qtp38569528-111) [n:127.0.0.1:56490_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> 34135 INFO (qtp38569528-111) [n:127.0.0.1:56490_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=6489
[junit4] 2> 34228 INFO (qtp2122114411-99) [n:127.0.0.1:56489_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> 34477 INFO (zkCallback-46-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> 34478 INFO (zkCallback-46-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> 34478 INFO (zkCallback-53-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> 34478 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> 34518 INFO (zkCallback-53-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> 34522 INFO (qtp2122114411-99) [n:127.0.0.1:56489_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=7479
[junit4] 2> 34787 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):45
[junit4] 2> 34834 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 34954 INFO (TEST-StreamDecoratorTest.testUpdateStream-seed#[C3AAD41545BDA373]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testUpdateStream
[junit4] 2> 35400 INFO (OverseerCollectionConfigSetProcessor-72075598312701964-127.0.0.1:56487_solr-n_0000000000) [n:127.0.0.1:56487_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 35998 INFO (qtp38569528-114) [n:127.0.0.1:56490_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> 35998 INFO (qtp38569528-114) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:56487/solr/collection1_shard1_replica_n1/&wt=javabin&version=2}{deleteByQuery=*:* (-1638978852391747584)} 0 360
[junit4] 2> 36026 INFO (qtp38569528-115) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1638978852838440960,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 36026 INFO (qtp38569528-115) [n:127.0.0.1:56490_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> 36029 INFO (qtp38569528-115) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 36029 INFO (qtp38569528-115) [n:127.0.0.1:56490_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [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:56487/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 9
[junit4] 2> 36031 INFO (qtp743374335-96) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 start commit{_version_=1638978852849975296,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 36031 INFO (qtp743374335-96) [n:127.0.0.1:56487_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> 36033 INFO (qtp743374335-96) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.DirectUpdateHandler2 end_commit_flush
[junit4] 2> 36042 INFO (qtp743374335-96) [n:127.0.0.1:56487_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> 36042 INFO (qtp743374335-96) [n:127.0.0.1:56487_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:5&commit=true&wt=javabin&version=2}{deleteByQuery=*:* (-1638978852014260224),commit=} 0 929
[junit4] 2> 36063 INFO (qtp2122114411-100) [n:127.0.0.1:56489_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=destinationCollection&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 36072 INFO (OverseerThreadFactory-24-thread-2-processing-n:127.0.0.1:56487_solr) [n:127.0.0.1:56487_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection destinationCollection
[junit4] 2> 36324 INFO (OverseerStateUpdate-72075598312701964-127.0.0.1:56487_solr-n_0000000000) [n:127.0.0.1:56487_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"destinationCollection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"destinationCollection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:56487/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 36331 INFO (OverseerStateUpdate-72075598312701964-127.0.0.1:56487_solr-n_0000000000) [n:127.0.0.1:56487_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"destinationCollection",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"destinationCollection_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:56490/solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 36569 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr x:destinationCollection_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=destinationCollection_shard2_replica_n2&action=CREATE&numShards=2&collection=destinationCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 36569 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr x:destinationCollection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=destinationCollection_shard1_replica_n1&action=CREATE&numShards=2&collection=destinationCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 37744 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 37863 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema [destinationCollection_shard2_replica_n2] Schema name=test
[junit4] 2> 37951 WARN (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 37953 WARN (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 37953 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 37954 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'destinationCollection_shard2_replica_n2' using configuration from collection destinationCollection, trusted=true
[junit4] 2> 37954 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56490.solr.core.destinationCollection.shard2.replica_n2' (registry 'solr.core.destinationCollection.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 37954 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.SolrCore [[destinationCollection_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node4\destinationCollection_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node4\.\destinationCollection_shard2_replica_n2\data\]
[junit4] 2> 37968 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 37990 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema [destinationCollection_shard1_replica_n1] Schema name=test
[junit4] 2> 38053 WARN (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiple copyFields (2)
[junit4] 2> 38054 WARN (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 38054 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 38054 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'destinationCollection_shard1_replica_n1' using configuration from collection destinationCollection, trusted=true
[junit4] 2> 38056 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_56487.solr.core.destinationCollection.shard1.replica_n1' (registry 'solr.core.destinationCollection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@67ee2427
[junit4] 2> 38056 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.SolrCore [[destinationCollection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node2\destinationCollection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001\tempDir-001\node2\.\destinationCollection_shard1_replica_n1\data\]
[junit4] 2> 38441 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 38441 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 38448 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 38448 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 38516 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 38516 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 38520 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 38520 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 38573 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@40b8568e[destinationCollection_shard2_replica_n2] main]
[junit4] 2> 38574 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 38574 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 38578 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 38578 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1638978855520698368
[junit4] 2> 38609 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/destinationCollection/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 38610 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@af2b864[destinationCollection_shard1_replica_n1] main]
[junit4] 2> 38614 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/destinationCollection/leaders/shard2
[junit4] 2> 38614 INFO (searcherExecutor-51-thread-1-processing-n:127.0.0.1:56490_solr x:destinationCollection_shard2_replica_n2 c:destinationCollection s:shard2 r:core_node4) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.SolrCore [destinationCollection_shard2_replica_n2] Registered new searcher Searcher@40b8568e[destinationCollection_shard2_replica_n2] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 38619 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 38623 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 38623 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 38624 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1638978855568932864
[junit4] 2> 38630 INFO (searcherExecutor-52-thread-1-processing-n:127.0.0.1:56487_solr x:destinationCollection_shard1_replica_n1 c:destinationCollection s:shard1 r:core_node3) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.SolrCore [destinationCollection_shard1_replica_n1] Registered new searcher Searcher@af2b864[destinationCollection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 38642 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 38642 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 38642 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56490/solr/destinationCollection_shard2_replica_n2/
[junit4] 2> 38644 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 38646 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:56490/solr/destinationCollection_shard2_replica_n2/ has no replicas
[junit4] 2> 38646 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/destinationCollection/leaders/shard2/leader after winning as /collections/destinationCollection/leader_elect/shard2/election/72075598312701965-core_node4-n_0000000000
[junit4] 2> 38650 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/destinationCollection/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 38652 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/destinationCollection/leaders/shard1
[junit4] 2> 38659 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:56490/solr/destinationCollection_shard2_replica_n2/ shard2
[junit4] 2> 38669 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 38669 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 38669 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:56487/solr/destinationCollection_shard1_replica_n1/
[junit4] 2> 38671 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 38671 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:56487/solr/destinationCollection_shard1_replica_n1/ has no replicas
[junit4] 2> 38671 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/destinationCollection/leaders/shard1/leader after winning as /collections/destinationCollection/leader_elect/shard1/election/72075598312701964-core_node3-n_0000000000
[junit4] 2> 38676 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:56487/solr/destinationCollection_shard1_replica_n1/ shard1
[junit4] 2> 38798 INFO (zkCallback-53-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/destinationCollection/state.json] for collection [destinationCollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 38798 INFO (zkCallback-53-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/destinationCollection/state.json] for collection [destinationCollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 38798 INFO (zkCallback-46-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/destinationCollection/state.json] for collection [destinationCollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 38798 INFO (zkCallback-46-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/destinationCollection/state.json] for collection [destinationCollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 38807 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 38807 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 38856 INFO (qtp38569528-110) [n:127.0.0.1:56490_solr c:destinationCollection s:shard2 r:core_node4 x:destinationCollection_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=destinationCollection_shard2_replica_n2&action=CREATE&numShards=2&collection=destinationCollection&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2018
[junit4] 2> 38859 INFO (qtp743374335-97) [n:127.0.0.1:56487_solr c:destinationCollection s:shard1 r:core_node3 x:destinationCollection_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=destinationCollection_shard1_replica_n1&action=CREATE&numShards=2&collection=destinationCollection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=2020
[junit4] 2> 38873 INFO (qtp2122114411-100) [n:127.0.0.1:56489_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> 38999 INFO (zkCallback-53-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/destinationCollection/state.json] for collection [destinationCollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 39001 INFO (zkCallback-53-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/destinationCollection/state.json] for collection [destinationCollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 39003 INFO (zkCallback-46-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/destinationCollection/state.json] for collection [destinationCollection] has occurred - updating... (live nodes size: [4])
[junit4] 2> 39003 INFO (zkCallback-46-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/destinationCollection/state.json] for collection [destinationCollection] has occurred - updating... (
[...truncated too long message...]
e.j.s.session node0 Stopped scavenging
[junit4] 2> 179362 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 179655 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> 71 /solr/aliases.json
[junit4] 2> 71 /solr/clusterprops.json
[junit4] 2> 13 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 12 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 12 /solr/collections/parallelDestinationCollection1/terms/shard2
[junit4] 2> 11 /solr/configs/conf
[junit4] 2> 11 /solr/collections/parallelDestinationCollection1/terms/shard1
[junit4] 2> 9 /solr/collections/destinationCollection/state.json
[junit4] 2> 7 /solr/collections/mainCorpus/state.json
[junit4] 2> 7 /solr/collections/parallelDestinationCollection/terms/shard1
[junit4] 2> 7 /solr/collections/parallelDestinationCollection/terms/shard2
[junit4] 2> 7 /solr/collections/destinationCollection/terms/shard2
[junit4] 2> 7 /solr/collections/destinationCollection/terms/shard1
[junit4] 2> 6 /solr/collections/destination1/state.json
[junit4] 2> 6 /solr/collections/mainCorpus1/state.json
[junit4] 2> 5 /solr/collections/destination/state.json
[junit4] 2> 4 /solr/collections/workQueue1/terms/shard2
[junit4] 2> 4 /solr/collections/destination1/terms/shard1
[junit4] 2> 4 /solr/collections/destinationCollection/collectionprops.json
[junit4] 2> 4 /solr/collections/destination1/terms/shard2
[junit4] 2> 4 /solr/collections/mainCorpus1/terms/shard1
[junit4] 2> 4 /solr/collections/mainCorpus1/terms/shard2
[junit4] 2> 4 /solr/collections/parallelDestinationCollection1/collectionprops.json
[junit4] 2> 4 /solr/collections/workQueue/state.json
[junit4] 2> 4 /solr/configs/ml
[junit4] 2> 4 /solr/collections/destination/terms/shard1
[junit4] 2> 4 /solr/collections/workQueue1/state.json
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/collections/mainCorpus/terms/shard1
[junit4] 2> 4 /solr/collections/workQueue/terms/shard2
[junit4] 2> 3 /solr/collections/workQueue1/terms/shard1
[junit4] 2> 3 /solr/collections/modelCollection/terms/shard2
[junit4] 2> 3 /solr/collections/modelCollection/terms/shard1
[junit4] 2> 3 /solr/collections/parallelDestinationCollection/collectionprops.json
[junit4] 2> 3 /solr/collections/destination/terms/shard2
[junit4] 2> 3 /solr/collections/checkpointCollection/terms/shard1
[junit4] 2> 3 /solr/collections/mainCorpus/terms/shard2
[junit4] 2> 3 /solr/collections/collection1/terms/shard1
[junit4] 2> 3 /solr/collections/collection1/terms/shard2
[junit4] 2> 3 /solr/collections/uknownCollection/terms/shard2
[junit4] 2> 3 /solr/collections/uknownCollection/terms/shard1
[junit4] 2> 3 /solr/collections/workQueue/terms/shard1
[junit4] 2> 2 /solr/collections/collection1/collectionprops.json
[junit4] 2> 2 /solr/collections/mainCorpus1/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue/collectionprops.json
[junit4] 2> 2 /solr/collections/destination/collectionprops.json
[junit4] 2> 2 /solr/collections/destination1/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue1/collectionprops.json
[junit4] 2> 2 /solr/collections/checkpointCollection/terms/shard2
[junit4] 2> 2 /solr/collections/modelCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/uknownCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/mainCorpus/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 74 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 71 /solr/clusterstate.json
[junit4] 2> 48 /solr/collections/destinationCollection/state.json
[junit4] 2> 40 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 33 /solr/collections/uknownCollection/state.json
[junit4] 2> 27 /solr/collections/modelCollection/state.json
[junit4] 2> 26 /solr/collections/workQueue1/state.json
[junit4] 2> 26 /solr/collections/workQueue/state.json
[junit4] 2> 25 /solr/collections/checkpointCollection/state.json
[junit4] 2> 23 /solr/collections/collection1/state.json
[junit4] 2> 22 /solr/collections/mainCorpus1/state.json
[junit4] 2> 21 /solr/collections/destination/state.json
[junit4] 2> 20 /solr/collections/mainCorpus/state.json
[junit4] 2> 18 /solr/collections/destination1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 351 /solr/collections
[junit4] 2> 270 /solr/overseer/queue
[junit4] 2> 94 /solr/overseer/collection-queue-work
[junit4] 2> 92 /solr/live_nodes
[junit4] 2>
[junit4] 2> 179713 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:56474
[junit4] 2> 179713 INFO (SUITE-StreamDecoratorTest-seed#[C3AAD41545BDA373]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 56474
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.io.stream.StreamDecoratorTest_C3AAD41545BDA373-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {expr_s=PostingsFormat(name=LuceneVarGapFixedInterval), name_s=FST50, terms_ss=PostingsFormat(name=Asserting), join2_s=PostingsFormat(name=Asserting), field_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), multiDefault=PostingsFormat(name=Asserting), subject=PostingsFormat(name=Asserting), a1_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), a2_s=PostingsFormat(name=LuceneVarGapFixedInterval), body_t=FST50, s_multi=PostingsFormat(name=LuceneVarGapFixedInterval), a_ss=PostingsFormat(name=Asserting), a_s=PostingsFormat(name=LuceneVarGapFixedInterval), tv_text=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), text_s=PostingsFormat(name=LuceneVarGapFixedInterval), id=FST50, text=PostingsFormat(name=LuceneVarGapFixedInterval), bbid_s=FST50, type_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), checkpoint_ss=FST50, test_t=FST50, ident_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), extra_s=PostingsFormat(name=Asserting), ykey_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene50(blocksize=128))), side_s=FST50}, docValues:{expr_s=DocValuesFormat(name=Lucene80), name_s=DocValuesFormat(name=Direct), iteration_i=DocValuesFormat(name=Direct), terms_ss=DocValuesFormat(name=Asserting), join2_s=DocValuesFormat(name=Asserting), field_s=DocValuesFormat(name=Lucene80), multiDefault=DocValuesFormat(name=Asserting), a1_s=DocValuesFormat(name=Lucene80), a_f=DocValuesFormat(name=Lucene80), intDefault=DocValuesFormat(name=Lucene80), join1_i=DocValuesFormat(name=Lucene80), falseNegative_i=DocValuesFormat(name=Lucene80), a2_s=DocValuesFormat(name=Lucene80), a_i=DocValuesFormat(name=Direct), join3_i=DocValuesFormat(name=Direct), test_i=DocValuesFormat(name=Asserting), falsePositive_i=DocValuesFormat(name=Lucene80), field_i=DocValuesFormat(name=Asserting), s_multi=DocValuesFormat(name=Lucene80), a_ss=DocValuesFormat(name=Asserting), a_s=DocValuesFormat(name=Lucene80), text_s=DocValuesFormat(name=Lucene80), id=DocValuesFormat(name=Direct), bbid_s=DocValuesFormat(name=Direct), timestamp=DocValuesFormat(name=Asserting), type_s=DocValuesFormat(name=Lucene80), i_multi=DocValuesFormat(name=Direct), trueNegative_i=DocValuesFormat(name=Lucene80), b_ls=DocValuesFormat(name=Lucene80), out_i=DocValuesFormat(name=Lucene80), checkpoint_ss=DocValuesFormat(name=Direct), ident_s=DocValuesFormat(name=Lucene80), _version_=DocValuesFormat(name=Lucene80), extra_s=DocValuesFormat(name=Asserting), ykey_s=DocValuesFormat(name=Lucene80), side_s=DocValuesFormat(name=Direct), truePositive_i=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=18, maxMBSortInHeap=7.087017035918123, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@3c926108), locale=en-SS, timezone=AET
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.1 (64-bit)/cpus=3,threads=1,free=92555896,total=292552704
[junit4] 2> NOTE: All tests run in this JVM: [ShardParamsTest, StreamDecoratorTest]
[junit4] Completed [25/199 (1!)] on J0 in 177.10s, 49 tests, 1 failure <<< FAILURES!
[...truncated 44323 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8052/consoleText
[repro] Revision: 7ddba3b7123f11d387ff395516cf46c607ac21ee
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\solrj
[repro] StreamDecoratorTest
[repro] ant compile-test
[...truncated 2529 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.StreamDecoratorTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseG1GC" -Dtests.seed=C3AAD41545BDA373 -Dtests.slow=true -Dtests.locale=en-SS -Dtests.timezone=AET -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 313 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[repro] Exiting with code 0
[...truncated 78 lines...]
[JENKINS] Lucene-Solr-master-Windows (64bit/jdk-12.0.1) - Build #
8053 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8053/
Java: 64bit/jdk-12.0.1 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.core.TestDynamicLoading.testDynamicLoading
Error Message:
Could not get expected value 'org.apache.solr.core.BlobStoreTestRequestHandler' for path 'overlay/requestHandler/\/test1/class' full output: { "responseHeader":{ "status":0, "QTime":0}, "overlay":{ "znodeVersion":0, "runtimeLib":{"colltest":{ "name":"colltest", "version":1}}}}, from server: null
Stack Trace:
java.lang.AssertionError: Could not get expected value 'org.apache.solr.core.BlobStoreTestRequestHandler' for path 'overlay/requestHandler/\/test1/class' full output: {
"responseHeader":{
"status":0,
"QTime":0},
"overlay":{
"znodeVersion":0,
"runtimeLib":{"colltest":{
"name":"colltest",
"version":1}}}}, from server: null
at __randomizedtesting.SeedInfo.seed([A8AFB3576FDF1633:70E29E009802B393]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.assertTrue(Assert.java:41)
at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:590)
at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:80)
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:567)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
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:835)
Build Log:
[...truncated 14885 lines...]
[junit4] Suite: org.apache.solr.core.TestDynamicLoading
[junit4] 2> 4091208 INFO (SUITE-TestDynamicLoading-seed#[A8AFB3576FDF1633]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> Creating dataDir: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\init-core-data-001
[junit4] 2> 4091208 INFO (SUITE-TestDynamicLoading-seed#[A8AFB3576FDF1633]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 4091214 INFO (SUITE-TestDynamicLoading-seed#[A8AFB3576FDF1633]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 4091214 INFO (SUITE-TestDynamicLoading-seed#[A8AFB3576FDF1633]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 4091220 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 4091220 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 4091220 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 4091334 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer start zk server on port:50150
[junit4] 2> 4091334 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:50150
[junit4] 2> 4091334 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 50150
[junit4] 2> 4091336 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4091342 INFO (zkConnectionManagerCallback-20325-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4091342 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4091346 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4091348 INFO (zkConnectionManagerCallback-20327-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4091348 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4091350 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 4091353 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 4091355 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 4091358 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 4091359 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 4091362 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 4091363 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 4091365 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 4091366 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 4091368 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 4091370 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkTestServer put C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 4091372 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 4093419 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4093420 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4093420 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4093420 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 4093420 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4093420 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4093420 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4093420 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d96f3b5{/,null,AVAILABLE}
[junit4] 2> 4093422 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1a1402b8{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50160}
[junit4] 2> 4093422 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.s.Server Started @4147198ms
[junit4] 2> 4093422 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\tempDir-001/control/data, hostPort=50160, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\control-001\cores}
[junit4] 2> 4093422 ERROR (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4093422 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4093422 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4093422 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4093422 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4093422 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-14T12:02:57.915222800Z
[junit4] 2> 4093423 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4093430 INFO (zkConnectionManagerCallback-20329-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4093430 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4093545 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4093545 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\control-001\solr.xml
[junit4] 2> 4093550 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4093550 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4093550 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4093707 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4093709 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@12c37943[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4093709 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@12c37943[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4093715 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4763ae79[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4093716 WARN (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4763ae79[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4093718 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50150/solr
[junit4] 2> 4093719 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4093737 INFO (zkConnectionManagerCallback-20336-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4093737 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4093858 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4093860 INFO (zkConnectionManagerCallback-20338-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4093860 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4094040 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:50160_
[junit4] 2> 4094041 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.c.Overseer Overseer (id=72079161802293252-127.0.0.1:50160_-n_0000000000) starting
[junit4] 2> 4094050 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4094054 INFO (zkConnectionManagerCallback-20345-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4094054 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4094061 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50150/solr ready
[junit4] 2> 4094064 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50160_
[junit4] 2> 4094064 INFO (OverseerStateUpdate-72079161802293252-127.0.0.1:50160_-n_0000000000) [n:127.0.0.1:50160_ ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:50160_
[junit4] 2> 4094064 INFO (OverseerStateUpdate-72079161802293252-127.0.0.1:50160_-n_0000000000) [n:127.0.0.1:50160_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4094094 INFO (zkCallback-20344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4094113 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4094133 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4094142 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4094142 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4094144 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [n:127.0.0.1:50160_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\control-001\cores
[junit4] 2> 4094170 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4094174 INFO (zkConnectionManagerCallback-20351-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4094174 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4094175 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4094176 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50150/solr ready
[junit4] 2> 4094179 INFO (qtp2140240037-56002) [n:127.0.0.1:50160_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:50160_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4094183 INFO (OverseerThreadFactory-17140-thread-1-processing-n:127.0.0.1:50160_) [n:127.0.0.1:50160_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 4094304 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ x:control_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4094304 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ x:control_collection_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4095469 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 4095491 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 4095621 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4095659 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 4095659 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4095659 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\control-001\cores\control_collection_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\control-001\cores\control_collection_shard1_replica_n1\data\]
[junit4] 2> 4095663 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=54.71875, floorSegmentMB=0.57421875, forceMergeDeletesPctAllowed=27.446650078021683, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.377799359481242, deletesPctAllowed=49.63243141312465
[junit4] 2> 4095664 WARN (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_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> 4095824 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4095824 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4095829 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4095829 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4095830 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2136308559, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 4095831 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@3f112819[control_collection_shard1_replica_n1] main]
[junit4] 2> 4095833 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 4095833 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 4095834 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 4095834 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1639035206712164352
[junit4] 2> 4095838 INFO (searcherExecutor-17145-thread-1-processing-n:127.0.0.1:50160_ x:control_collection_shard1_replica_n1 c:control_collection s:shard1) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@3f112819[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 4095843 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/control_collection/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 4095843 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/control_collection/leaders/shard1
[junit4] 2> 4095849 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 4095849 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 4095849 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:50160/control_collection_shard1_replica_n1/
[junit4] 2> 4095850 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 4095850 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:50160/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 4095850 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/control_collection/leaders/shard1/leader after winning as /collections/control_collection/leader_elect/shard1/election/72079161802293252-core_node2-n_0000000000
[junit4] 2> 4095853 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:50160/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 4095969 INFO (zkCallback-20337-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4095970 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 4095975 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1 ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1473
[junit4] 2> 4095978 INFO (qtp2140240037-56002) [n:127.0.0.1:50160_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 4096086 INFO (zkCallback-20337-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4096086 INFO (zkCallback-20337-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 4096087 INFO (qtp2140240037-56002) [n:127.0.0.1:50160_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:50160_&wt=javabin&version=2} status=0 QTime=1685
[junit4] 2> 4096209 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4096211 INFO (zkConnectionManagerCallback-20357-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4096211 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4096213 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4096217 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50150/solr ready
[junit4] 2> 4096217 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 4096219 INFO (qtp2140240037-56003) [n:127.0.0.1:50160_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4096222 INFO (OverseerCollectionConfigSetProcessor-72079161802293252-127.0.0.1:50160_-n_0000000000) [n:127.0.0.1:50160_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 4096224 INFO (OverseerThreadFactory-17140-thread-2-processing-n:127.0.0.1:50160_) [n:127.0.0.1:50160_ ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 4096458 WARN (OverseerThreadFactory-17140-thread-2-processing-n:127.0.0.1:50160_) [n:127.0.0.1:50160_ ] o.a.s.c.a.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 4096459 INFO (qtp2140240037-56003) [n:127.0.0.1:50160_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 4096480 INFO (qtp2140240037-56003) [n:127.0.0.1:50160_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=2&wt=javabin&version=2} status=0 QTime=230
[junit4] 2> 4096494 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Creating jetty instances pullReplicaCount=0 numOtherReplicas=4
[junit4] 2> 4098531 INFO (OverseerCollectionConfigSetProcessor-72079161802293252-127.0.0.1:50160_-n_0000000000) [n:127.0.0.1:50160_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 4098740 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-1-001 of type NRT
[junit4] 2> 4098741 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4098741 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4098741 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4098741 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 4098741 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4098741 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4098741 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4098742 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@537018e1{/,null,AVAILABLE}
[junit4] 2> 4098742 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@513f5554{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50204}
[junit4] 2> 4098742 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.Server Started @4151846ms
[junit4] 2> 4098742 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\tempDir-001/jetty1, hostPort=50204, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-1-001\cores, replicaType=NRT}
[junit4] 2> 4098742 ERROR (closeThreadPool-20358-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4098742 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4098744 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4098744 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4098744 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4098744 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-14T12:03:03.237928100Z
[junit4] 2> 4098745 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4098749 INFO (zkConnectionManagerCallback-20360-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4098749 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4098875 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4098875 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-1-001\solr.xml
[junit4] 2> 4098879 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4098879 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4098879 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4099199 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4099240 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@62be6e3b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4099240 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@62be6e3b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4099253 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@38caeea9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4099253 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@38caeea9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4099255 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50150/solr
[junit4] 2> 4099256 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4099303 INFO (zkConnectionManagerCallback-20367-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4099303 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4099435 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4099486 INFO (zkConnectionManagerCallback-20369-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4099487 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4099495 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 4099502 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.ZkController Publish node=127.0.0.1:50204_ as DOWN
[junit4] 2> 4099503 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4099503 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50204_
[junit4] 2> 4099508 INFO (zkCallback-20337-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4099508 INFO (zkCallback-20344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4099512 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4099539 INFO (zkCallback-20368-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4099539 INFO (zkCallback-20356-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 4099548 INFO (zkConnectionManagerCallback-20376-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4099548 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4099551 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 4099559 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50150/solr ready
[junit4] 2> 4099624 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4099715 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4099741 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4099741 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4099744 INFO (closeThreadPool-20358-thread-1) [n:127.0.0.1:50204_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-1-001\cores
[junit4] 2> 4101584 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-2-001 of type NRT
[junit4] 2> 4101584 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4101584 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4101584 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4101584 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 4101584 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4101584 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4101584 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4101584 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4053daee{/,null,AVAILABLE}
[junit4] 2> 4101591 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@42d8412a{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50246}
[junit4] 2> 4101591 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.Server Started @4154132ms
[junit4] 2> 4101591 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\tempDir-001/jetty2, hostPort=50246, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-2-001\cores}
[junit4] 2> 4101591 ERROR (closeThreadPool-20358-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4101591 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4101591 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4101591 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4101591 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4101591 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-14T12:03:06.084298400Z
[junit4] 2> 4101591 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4101600 INFO (zkConnectionManagerCallback-20379-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4101600 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4101728 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4101728 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-2-001\solr.xml
[junit4] 2> 4101730 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4101730 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4101760 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4102541 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4102625 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4f207abe[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4102625 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4f207abe[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4102662 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@378dccc3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4102662 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@378dccc3[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4102669 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50150/solr
[junit4] 2> 4102670 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4102762 INFO (zkConnectionManagerCallback-20386-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4102762 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4102889 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4102965 INFO (zkConnectionManagerCallback-20388-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4102965 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4102979 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 4102990 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:50246_ as DOWN
[junit4] 2> 4102992 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4102992 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50246_
[junit4] 2> 4102996 INFO (zkCallback-20344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4102996 INFO (zkCallback-20368-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4102996 INFO (zkCallback-20356-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4102996 INFO (zkCallback-20337-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4103001 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4103020 INFO (zkCallback-20387-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4103039 INFO (zkCallback-20375-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 4103119 INFO (zkConnectionManagerCallback-20395-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4103119 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4103122 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 4103127 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50150/solr ready
[junit4] 2> 4103190 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4103236 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4103260 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4103260 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4103262 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-2-001\cores
[junit4] 2> 4104637 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-3-001 of type NRT
[junit4] 2> 4104638 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4104638 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4104638 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4104638 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 4104638 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4104638 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4104638 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 4104638 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1682841d{/,null,AVAILABLE}
[junit4] 2> 4104640 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@41137cfa{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50329}
[junit4] 2> 4104640 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.Server Started @4156580ms
[junit4] 2> 4104640 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\tempDir-001/jetty3, hostPort=50329, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-3-001\cores}
[junit4] 2> 4104642 ERROR (closeThreadPool-20358-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4104642 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4104642 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4104642 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4104642 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4104642 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-14T12:03:09.135077300Z
[junit4] 2> 4104642 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4104647 INFO (zkConnectionManagerCallback-20398-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4104647 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4104775 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4104775 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-3-001\solr.xml
[junit4] 2> 4104778 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4104778 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4104780 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4105068 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4105071 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2cc32676[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4105071 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2cc32676[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4105074 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@32d8be42[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4105074 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@32d8be42[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4105074 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50150/solr
[junit4] 2> 4105080 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4105084 INFO (zkConnectionManagerCallback-20405-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4105084 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4105211 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4105214 INFO (zkConnectionManagerCallback-20407-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4105214 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4105223 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 4105226 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:50329_ as DOWN
[junit4] 2> 4105226 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4105226 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50329_
[junit4] 2> 4105232 INFO (zkCallback-20368-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4105232 INFO (zkCallback-20387-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4105232 INFO (zkCallback-20344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4105232 INFO (zkCallback-20356-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4105232 INFO (zkCallback-20337-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4105232 INFO (zkCallback-20375-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4105233 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4105239 INFO (zkCallback-20406-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4105239 INFO (zkCallback-20394-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 4105243 INFO (zkConnectionManagerCallback-20414-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4105243 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4105245 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 4105245 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50150/solr ready
[junit4] 2> 4105288 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4105311 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4105321 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4105321 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4105321 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-3-001\cores
[junit4] 2> 4106711 INFO (TEST-TestDynamicLoading.testDynamicLoading-seed#[A8AFB3576FDF1633]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-4-001 of type NRT
[junit4] 2> 4106711 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.s.AbstractConnector Ignoring deprecated socket close linger time
[junit4] 2> 4106712 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (original configured port=0)
[junit4] 2> 4106712 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 1 ...
[junit4] 2> 4106712 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.Server jetty-9.4.19.v20190610; built: 2019-06-10T16:30:51.723Z; git: afcf563148970e98786327af5e07c261fda175d3; jvm 12.0.1+12
[junit4] 2> 4106713 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 4106713 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 4106713 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 4106713 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7d5a0a89{/,null,AVAILABLE}
[junit4] 2> 4106714 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@6e1b4073{HTTP/1.1,[http/1.1, h2c]}{127.0.0.1:50349}
[junit4] 2> 4106714 INFO (closeThreadPool-20358-thread-1) [ ] o.e.j.s.Server Started @4158244ms
[junit4] 2> 4106714 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/, solrconfig=solrconfig.xml, solr.data.dir=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\tempDir-001/jetty4, hostPort=50349, coreRootDirectory=C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-4-001\cores, replicaType=NRT}
[junit4] 2> 4106714 ERROR (closeThreadPool-20358-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 4106714 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 4106715 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 9.0.0
[junit4] 2> 4106715 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 4106715 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 4106715 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2019-07-14T12:03:11.208534700Z
[junit4] 2> 4106716 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4106720 INFO (zkConnectionManagerCallback-20417-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4106720 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4106849 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 4106849 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-4-001\solr.xml
[junit4] 2> 4106850 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 4106850 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 4106854 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 4107214 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 4107217 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@205a83a7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4107217 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@205a83a7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4107221 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@725c2ca0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4107221 WARN (closeThreadPool-20358-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@725c2ca0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 4107221 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:50150/solr
[junit4] 2> 4107223 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4107227 INFO (zkConnectionManagerCallback-20424-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4107227 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4107356 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4107362 INFO (zkConnectionManagerCallback-20426-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4107362 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4107372 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 4107380 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:50349_ as DOWN
[junit4] 2> 4107383 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 4107383 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:50349_
[junit4] 2> 4107386 INFO (zkCallback-20344-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107386 INFO (zkCallback-20406-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107386 INFO (zkCallback-20337-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107386 INFO (zkCallback-20368-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107386 INFO (zkCallback-20387-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107386 INFO (zkCallback-20356-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107386 INFO (zkCallback-20375-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107386 INFO (zkCallback-20394-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107386 INFO (zkCallback-20425-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107391 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 4107392 INFO (zkCallback-20413-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (4) -> (5)
[junit4] 2> 4107400 INFO (zkConnectionManagerCallback-20433-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 4107400 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 4107402 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (5)
[junit4] 2> 4107403 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:50150/solr ready
[junit4] 2> 4107458 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 4107494 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4107507 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4107510 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4107513 INFO (closeThreadPool-20358-thread-1) [ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-4-001\cores
[junit4] 2> 4107625 INFO (qtp2140240037-56004) [n:127.0.0.1:50160_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:50329_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4107625 INFO (qtp2140240037-56002) [n:127.0.0.1:50160_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:50246_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4107625 INFO (qtp2140240037-56005) [n:127.0.0.1:50160_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:50349_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4107634 INFO (qtp2140240037-56006) [n:127.0.0.1:50160_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:50204_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 4107641 INFO (OverseerThreadFactory-17140-thread-3-processing-n:127.0.0.1:50160_) [n:127.0.0.1:50160_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:50329_ for creating new replica of shard shard2 for collection collection1
[junit4] 2> 4107648 INFO (OverseerThreadFactory-17140-thread-4-processing-n:127.0.0.1:50160_) [n:127.0.0.1:50160_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:50349_ for creating new replica of shard shard1 for collection collection1
[junit4] 2> 4107666 INFO (OverseerThreadFactory-17140-thread-4-processing-n:127.0.0.1:50160_) [n:127.0.0.1:50160_ c:collection1 s:shard1 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 4107668 INFO (OverseerThreadFactory-17140-thread-3-processing-n:127.0.0.1:50160_) [n:127.0.0.1:50160_ c:collection1 s:shard2 ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 4107681 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ x:collection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n1&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4107689 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ x:collection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n2&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 4108966 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 4108988 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
[junit4] 2> 4109005 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema [collection1_shard1_replica_n1] Schema name=test
[junit4] 2> 4109006 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema [collection1_shard2_replica_n2] Schema name=test
[junit4] 2> 4109126 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4109180 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 4109183 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from collection collection1, trusted=true
[junit4] 2> 4109184 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4109184 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[collection1_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-4-001\cores\collection1_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-4-001\cores\collection1_shard1_replica_n1\data\]
[junit4] 2> 4109189 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=54.71875, floorSegmentMB=0.57421875, forceMergeDeletesPctAllowed=27.446650078021683, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.377799359481242, deletesPctAllowed=49.63243141312465
[junit4] 2> 4109214 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from collection collection1, trusted=true
[junit4] 2> 4109214 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@aabe4cd
[junit4] 2> 4109216 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[collection1_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-3-001\cores\collection1_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001\shard-3-001\cores\collection1_shard2_replica_n2\data\]
[junit4] 2> 4109221 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=47, maxMergeAtOnceExplicit=16, maxMergedSegmentMB=54.71875, floorSegmentMB=0.57421875, forceMergeDeletesPctAllowed=27.446650078021683, segmentsPerTier=42.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.377799359481242, deletesPctAllowed=49.63243141312465
[junit4] 2> 4109221 WARN (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_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> 4109253 WARN (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] 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> 4109340 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4109340 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4109345 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4109345 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4109354 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=2136308559, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1]
[junit4] 2> 4109370 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 4109370 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 4109371 INFO (qtp1539926046-56168) [n:127.0.0.1:50349_ c:collection1 s:shard1 x:collection1_shard1_replica_n1 ] o.a.s.s.SolrIndexSearcher Opening [Searcher@4991354e[collection1_shard1_replica_n1] main]
[junit4] 2> 4109371 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 4109371 INFO (qtp1930329250-56133) [n:127.0.0.1:50329_ c:collection1 s:shard2 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 4109376 INFO (qtp1539926046-56168)
[...truncated too long message...]
t4] > at __randomizedtesting.SeedInfo.seed([A8AFB3576FDF1633:70E29E009802B393]:0)
[junit4] > at org.apache.solr.core.TestSolrConfigHandler.testForResponseElement(TestSolrConfigHandler.java:590)
[junit4] > at org.apache.solr.core.TestDynamicLoading.testDynamicLoading(TestDynamicLoading.java:80)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:567)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1082)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1054)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:835)
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-master-Windows\solr\build\solr-core\test\J0\temp\solr.core.TestDynamicLoading_A8AFB3576FDF1633-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene80): {}, docValues:{}, maxPointsInLeafNode=590, maxMBSortInHeap=6.922116556542037, sim=Asserting(org.apache.lucene.search.similarities.AssertingSimilarity@4ebeceb0), locale=luy, timezone=Asia/Ashgabat
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 12.0.1 (64-bit)/cpus=3,threads=1,free=134725128,total=428236800
[junit4] 2> NOTE: All tests run in this JVM: [BlockJoinFacetSimpleTest, TestGroupingSearch, TermVectorComponentDistributedTest, TestNoOpRegenerator, TestSolrXml, TaggingAttributeTest, DebugComponentTest, TestFieldCacheVsDocValues, TestSubQueryTransformer, DistributedQueryComponentCustomSortTest, SolrInfoBeanTest, RollingRestartTest, CoreMergeIndexesAdminHandlerTest, ChaosMonkeySafeLeaderTest, WordBreakSolrSpellCheckerTest, ConnectionReuseTest, TestRTimerTree, TestDynamicLoadingUrl, VMParamsZkACLAndCredentialsProvidersTest, TestSimExtremeIndexing, ClusterStateUpdateTest, TestHdfsUpdateLog, ZkSolrClientTest, TestFuzzyAnalyzedSuggestions, TestGraphMLResponseWriter, TestNamedUpdateProcessors, ZkControllerTest, CdcrUpdateLogTest, TestSolrCoreSnapshots, ExecutePlanActionTest, TestInitQParser, SpellCheckComponentTest, DocValuesNotIndexedTest, SpellCheckCollatorWithCollapseTest, ReplicationFactorTest, TestPrepRecovery, RuleEngineTest, LeaderVoteWaitTimeoutTest, MoreLikeThisHandlerTest, TestApiFramework, TriggerSetPropertiesIntegrationTest, DistribDocExpirationUpdateProcessorTest, PeerSyncWithLeaderAndIndexFingerprintCachingTest, TestQuerySenderNoQuery, AssignBackwardCompatibilityTest, PluginInfoTest, MoreLikeThisComponentTest, MBeansHandlerTest, TestSerializedLuceneMatchVersion, TestBulkSchemaAPI, TestPhraseSuggestions, MetricsHistoryIntegrationTest, TestCoreBackup, ClassificationUpdateProcessorIntegrationTest, SolrXmlInZkTest, FieldMutatingUpdateProcessorTest, TestSlowCompositeReaderWrapper, TestRetrieveFieldsOptimizer, TestShardHandlerFactory, TestFoldingMultitermQuery, DistributedExpandComponentTest, DOMUtilTest, SolrCLIZkUtilsTest, IndexBasedSpellCheckerTest, EnumFieldTest, TestHalfAndHalfDocValues, HdfsChaosMonkeySafeLeaderTest, DistributedFacetPivotLargeTest, TestBinaryResponseWriter, AddBlockUpdateTest, TestFileDictionaryLookup, TestSolrConfigHandler, StressHdfsTest, TestCryptoKeys, TestImpersonationWithHadoopAuth, SolrCmdDistributorTest, LeaderElectionTest, SolrJmxReporterCloudTest, TestConfigSetProperties, SolrSlf4jReporterTest, TestSolrCoreProperties, HdfsBasicDistributedZkTest, Tagger2Test, TestReRankQParserPlugin, SolrMetricManagerTest, TestInPlaceUpdatesStandalone, TestSweetSpotSimilarityFactory, QueryElevationComponentTest, TestCorePropertiesReload, CoreAdminOperationTest, TestManagedResourceStorage, ShardRoutingCustomTest, TestStressVersions, SecurityConfHandlerTest, TestSolr4Spatial, TestHttpServletCarrier, TestFieldCacheSort, JSONWriterTest, TestRecovery, HDFSCollectionsAPITest, TestExpandComponent, DistributedQueryElevationComponentTest, URLClassifyProcessorTest, TransactionLogTest, TimeRoutedAliasUpdateProcessorTest, ConvertedLegacyTest, DisMaxRequestHandlerTest, SignificantTermsQParserPluginTest, TestSha256AuthenticationProvider, SpellingQueryConverterTest, RequestLoggingTest, TriggerEventQueueTest, TemplateUpdateProcessorTest, HdfsThreadLeakTest, TestMultiValuedNumericRangeQuery, CollectionsAPIDistributedZkTest, TestFastLRUCache, DistributedQueueTest, CategoryRoutedAliasUpdateProcessorTest, NodeLostTriggerIntegrationTest, TestImplicitCoreProperties, TestDefaultStatsCache, TestCSVLoader, TriLevelCompositeIdRoutingTest, TestMiniSolrCloudClusterSSL, TestJsonFacetsWithNestedObjects, TestSolrCloudWithSecureImpersonation, TestMaxTokenLenTokenizer, TokenizerChainTest, TestJoin, UniqFieldsUpdateProcessorFactoryTest, TestBackupRepositoryFactory, SimpleMLTQParserTest, ScheduledMaintenanceTriggerTest, OutputWriterTest, TestPolicyCloud, BadIndexSchemaTest, ScriptEngineTest, DeleteReplicaTest, BigEndianAscendingWordSerializerTest, TestDirectoryFactory, EchoParamsTest, BlockDirectoryTest, AutoScalingHandlerTest, RegexBytesRefFilterTest, ExitableDirectoryReaderTest, TestDistribDocBasedVersion, TestMacroExpander, TestInPlaceUpdatesDistrib, TestMaxScoreQueryParser, PolyFieldTest, SuggesterWFSTTest, ConnectionManagerTest, TestCustomDocTransformer, DistanceFunctionTest, ForceLeaderWithTlogReplicasTest, DistributedFacetPivotSmallAdvancedTest, AnalyticsMergeStrategyTest, AutoAddReplicasIntegrationTest, FacetPivotSmallTest, SpatialRPTFieldTypeTest, TestLegacyField, OverseerTest, TestDynamicFieldCollectionResource, TestLeaderElectionZkExpiry, TestCoreContainer, DistributedSuggestComponentTest, TestPerFieldSimilarity, MetricsConfigTest, DistributedFacetPivotWhiteBoxTest, SuggesterTSTTest, TestLRUCache, CollectionTooManyReplicasTest, RAMDirectoryFactoryTest, CustomHighlightComponentTest, TestConfigOverlay, TestPayloadCheckQParserPlugin, AdminHandlersProxyTest, TestStressReorder, TestAnalyzedSuggestions, TestXmlQParser, PeerSyncWithBufferUpdatesTest, IgnoreLargeDocumentProcessorFactoryTest, TestJsonFacets, MoveReplicaTest, TestEmbeddedSolrServerAdminHandler, RegexBoostProcessorTest, CoreAdminCreateDiscoverTest, AtomicUpdateRemovalJavabinTest, SchemaVersionSpecificBehaviorTest, TestSQLHandlerNonCloud, ZkStateWriterTest, TestSimTriggerIntegration, AuthToolTest, TestCloudNestedDocsSort, TestSort, CustomCollectionTest, HttpTriggerListenerTest, TestLMDirichletSimilarityFactory, TestCodecSupport, TestSimNodeLostTrigger, NodeAddedTriggerIntegrationTest, TestRawResponseWriter, ShardsWhitelistTest, MetricUtilsTest, TestTrie, AnalysisAfterCoreReloadTest, DocValuesMultiTest, ConcurrentCreateRoutedAliasTest, DeleteStatusTest, BasicFunctionalityTest, MinimalSchemaTest, SampleTest, TestCrossCoreJoin, TestDistributedGrouping, TestDistributedSearch, TestSimpleTrackingShardHandler, AddReplicaTest, BasicDistributedZkTest, CloudExitableDirectoryReaderTest, ClusterStateTest, CollectionStateFormat2Test, ConfigSetsAPITest, CreateRoutedAliasTest, DeleteLastCustomShardedReplicaTest, DistribJoinFromCollectionTest, DistributedVersionInfoTest, FullSolrCloudDistribCmdsTest, HealthCheckHandlerTest, HttpPartitionOnCommitTest, HttpPartitionTest, LeaderFailureAfterFreshStartTest, MultiThreadedOCPTest, NodeMutatorTest, OverriddenZkACLAndCredentialsProvidersTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, OverseerStatusTest, PeerSyncReplicationTest, RemoteQueryErrorTest, ReplaceNodeNoTargetTest, RestartWhileUpdatingTest, RoutingToNodesWithPropertiesTest, SSLMigrationTest, ShardRoutingTest, SharedFSAutoReplicaFailoverTest, SliceStateTest, SolrCloudExampleTest, TestAuthenticationFramework, TestCloudConsistency, TestCloudDeleteByQuery, TestCloudPhrasesIdentificationComponent, TestCloudRecovery2, TestConfigSetsAPIExclusivity, TestDeleteCollectionOnDownNodes, TestDistributedMap, TestDownShardTolerantSearch, TestExclusionRuleCollectionAccess, TestHashPartitioner, TestRandomFlRTGCloud, TestRandomRequestDistribution, TestRequestForwarding, TestSegmentSorting, TestSizeLimitedDistributedMap, TestSolrCloudWithKerberosAlt, TestStressInPlaceUpdates, TestUtilizeNode, TestZkChroot, ZkCLITest, AsyncCallRequestStatusResponseTest, CollectionReloadTest, ShardSplitTest, ScheduledTriggerIntegrationTest, ScheduledTriggerTest, SearchRateTriggerIntegrationTest, SearchRateTriggerTest, TestSimClusterStateProvider, TestSimDistributedQueue, TestSimGenericDistributedQueue, TestSimNodeAddedTrigger, TestSimPolicyCloud, BaseCdcrDistributedZkTest, CdcrBootstrapTest, RulesTest, ByteBuffersDirectoryFactoryTest, CachingDirectoryFactoryTest, TestDynamicLoading]
[junit4] Completed [598/867 (1!)] on J0 in 41.94s, 1 test, 1 failure <<< FAILURES!
[...truncated 45787 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Windows/8053/consoleText
[repro] Revision: 1a53d868821ec99c471f2df53bd12e33166b7ff9
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC"
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\core
[repro] TestDynamicLoading
[repro] ant compile-test
[...truncated 3337 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.TestDynamicLoading" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseSerialGC" -Dtests.seed=A8AFB3576FDF1633 -Dtests.slow=true -Dtests.locale=luy -Dtests.timezone=Asia/Ashgabat -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 73 lines...]
[repro] Failures:
[repro] 0/5 failed: org.apache.solr.core.TestDynamicLoading
[repro] Exiting with code 0
[...truncated 78 lines...]