You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/07/10 15:48:34 UTC
[JENKINS-Experimental-GC] Lucene-Solr-8.6-Windows
(64bit/jdk-14.0.1) - Build # 353 - Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.6-Windows/353/
Java: 64bit/jdk-14.0.1 -XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelTerminatingDaemonUpdateStream
Error Message:
Error from server at http://127.0.0.1:63499/solr: Underlying core creation failed while creating collection: parallelDestinationCollection1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at http://127.0.0.1:63499/solr: Underlying core creation failed while creating collection: parallelDestinationCollection1
at __randomizedtesting.SeedInfo.seed([D46C8C86A64FBAD6:250854EEF22575C8]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:681)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:370)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:298)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1157)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:918)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:850)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
at org.apache.solr.client.solrj.io.stream.StreamDecoratorTest.testParallelTerminatingDaemonUpdateStream(StreamDecoratorTest.java:3048)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:832)
Build Log:
[...truncated 17498 lines...]
[junit4] Suite: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[junit4] 2> 117530 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 117532 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\data-dir-10-001
[junit4] 2> 117532 WARN (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 117532 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 117535 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl="None")
[junit4] 2> 117535 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 117537 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 4 servers in C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001
[junit4] 2> 117537 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 117538 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 117538 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 117652 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 63470
[junit4] 2> 117652 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:63470
[junit4] 2> 117652 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63470
[junit4] 2> 117652 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63470
[junit4] 2> 117654 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 117659 INFO (zkConnectionManagerCallback-428-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 117659 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 117662 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 117664 INFO (zkConnectionManagerCallback-430-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 117664 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 117665 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 117668 INFO (zkConnectionManagerCallback-432-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 117668 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 117773 WARN (jetty-launcher-433-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 117773 WARN (jetty-launcher-433-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 117773 WARN (jetty-launcher-433-thread-3) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 117773 WARN (jetty-launcher-433-thread-4) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-3) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 117773 INFO (jetty-launcher-433-thread-4) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 14.0.1+7
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-3) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-4) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-4) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-4) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-3) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-3) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-4) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@312532c5{/solr,null,AVAILABLE}
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@7bd5b54e{/solr,null,AVAILABLE}
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-3) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6109e9ec{/solr,null,AVAILABLE}
[junit4] 2> 117776 INFO (jetty-launcher-433-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@5b4e8abf{/solr,null,AVAILABLE}
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-4) [ ] o.e.j.s.AbstractConnector Started ServerConnector@21330acb{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:63499}
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@221b8f0{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:63501}
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-4) [ ] o.e.j.s.Server Started @117815ms
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-2) [ ] o.e.j.s.Server Started @117815ms
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-3) [ ] o.e.j.s.AbstractConnector Started ServerConnector@373f7a2e{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:63502}
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4006e061{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:63500}
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-3) [ ] o.e.j.s.Server Started @117815ms
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-1) [ ] o.e.j.s.Server Started @117815ms
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63501}
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63499}
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63500}
[junit4] 2> 117782 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=63502}
[junit4] 2> 117783 ERROR (jetty-launcher-433-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 117783 ERROR (jetty-launcher-433-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 117783 ERROR (jetty-launcher-433-thread-3) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-10T15:27:46.019298800Z
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-10T15:27:46.019298800Z
[junit4] 2> 117783 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-10T15:27:46.019298800Z
[junit4] 2> 117784 ERROR (jetty-launcher-433-thread-4) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 117784 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 117784 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.6.0
[junit4] 2> 117784 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 117784 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr
[junit4] 2> 117784 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-07-10T15:27:46.020380100Z
[junit4] 2> 117784 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 117784 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 117784 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 117785 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 117787 INFO (zkConnectionManagerCallback-435-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 117787 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 117787 INFO (zkConnectionManagerCallback-441-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 117787 INFO (zkConnectionManagerCallback-437-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 117787 INFO (zkConnectionManagerCallback-439-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 117787 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 117787 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 117787 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 117788 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 117788 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 117788 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 117788 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 117981 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 117984 WARN (jetty-launcher-433-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5592dfbb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 117984 WARN (jetty-launcher-433-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5592dfbb[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 117993 WARN (jetty-launcher-433-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@68e541a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 117993 WARN (jetty-launcher-433-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@68e541a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 117993 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63470/solr
[junit4] 2> 117993 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 118000 INFO (zkConnectionManagerCallback-452-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 118000 INFO (jetty-launcher-433-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 118040 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 118042 WARN (jetty-launcher-433-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@555d4a30[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118042 WARN (jetty-launcher-433-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@555d4a30[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118048 WARN (jetty-launcher-433-thread-4) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@503698de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118048 WARN (jetty-launcher-433-thread-4) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@503698de[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118048 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63470/solr
[junit4] 2> 118052 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 118055 INFO (zkConnectionManagerCallback-463-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 118055 INFO (jetty-launcher-433-thread-4) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 118159 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 118203 INFO (zkConnectionManagerCallback-468-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 118203 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 118315 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 118331 INFO (zkConnectionManagerCallback-475-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 118331 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 118343 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:63500_solr
[junit4] 2> 118343 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.c.Overseer Overseer (id=72184456082948106-127.0.0.1:63500_solr-n_0000000000) starting
[junit4] 2> 118350 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63500_solr
[junit4] 2> 118355 INFO (OverseerStateUpdate-72184456082948106-127.0.0.1:63500_solr-n_0000000000) [n:127.0.0.1:63500_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:63500_solr
[junit4] 2> 118356 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 118356 INFO (zkCallback-474-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 118357 WARN (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 118373 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 118396 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 118404 WARN (jetty-launcher-433-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@69723ee9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118404 WARN (jetty-launcher-433-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6e996d2e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118407 WARN (jetty-launcher-433-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@69723ee9[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118408 WARN (jetty-launcher-433-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6e996d2e[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118412 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 118424 WARN (jetty-launcher-433-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@16cfa2a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118424 WARN (jetty-launcher-433-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@16cfa2a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118425 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63470/solr
[junit4] 2> 118428 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 118429 WARN (jetty-launcher-433-thread-3) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@49f3b3f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118429 WARN (jetty-launcher-433-thread-3) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@49f3b3f0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 118437 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:63470/solr
[junit4] 2> 118440 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 118460 INFO (zkConnectionManagerCallback-499-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 118460 INFO (jetty-launcher-433-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 118462 INFO (zkConnectionManagerCallback-502-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 118463 INFO (jetty-launcher-433-thread-3) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 118548 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63500.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118556 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63500.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118556 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63500.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118560 INFO (jetty-launcher-433-thread-1) [n:127.0.0.1:63500_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node1
[junit4] 2> 118565 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 118591 INFO (zkConnectionManagerCallback-505-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 118592 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 118608 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 118628 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 118636 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:63499_solr as DOWN
[junit4] 2> 118640 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 118640 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63499_solr
[junit4] 2> 118645 INFO (zkCallback-474-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 118646 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:63501_solr as DOWN
[junit4] 2> 118649 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 118649 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 118655 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63501_solr
[junit4] 2> 118656 WARN (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 118660 INFO (zkCallback-467-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 118660 INFO (zkCallback-504-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 118670 INFO (zkCallback-504-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 118670 INFO (zkCallback-467-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 118671 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 118672 INFO (zkCallback-474-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 118685 INFO (zkConnectionManagerCallback-510-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 118685 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 118692 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 118696 WARN (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 118732 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 118748 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 118757 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 118767 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:63502_solr as DOWN
[junit4] 2> 118768 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 118768 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:63502_solr
[junit4] 2> 118793 INFO (zkCallback-474-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 118797 INFO (zkCallback-467-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 118797 INFO (zkCallback-504-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 118800 INFO (zkCallback-509-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 118814 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63499.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118816 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 118816 WARN (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 118832 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63501.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118836 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63499.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118838 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63499.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118860 INFO (jetty-launcher-433-thread-4) [n:127.0.0.1:63499_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node4
[junit4] 2> 118872 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 118880 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63501.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118880 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63501.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118884 INFO (jetty-launcher-433-thread-2) [n:127.0.0.1:63501_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node2
[junit4] 2> 118910 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63502.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118922 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63502.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118922 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63502.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 118924 INFO (jetty-launcher-433-thread-3) [n:127.0.0.1:63502_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node3
[junit4] 2> 118973 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=4
[junit4] 2> 118976 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 118985 INFO (zkConnectionManagerCallback-534-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 118986 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 118990 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (4)
[junit4] 2> 118998 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:63470/solr ready
[junit4] 2> 119038 INFO (qtp804577605-1037) [n:127.0.0.1:63499_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> 119060 INFO (OverseerThreadFactory-482-thread-1-processing-n:127.0.0.1:63500_solr) [n:127.0.0.1:63500_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 119192 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=6
[junit4] 2> 119201 INFO (qtp216422172-1040) [n:127.0.0.1:63501_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=5
[junit4] 2> 119208 INFO (qtp1321273289-1034) [n:127.0.0.1:63500_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 119212 INFO (qtp1321273289-1034) [n:127.0.0.1:63500_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=6
[junit4] 2> 119230 INFO (qtp972587873-1035) [n:127.0.0.1:63502_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=5
[junit4] 2> 119261 INFO (OverseerStateUpdate-72184456082948106-127.0.0.1:63500_solr-n_0000000000) [n:127.0.0.1:63500_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:63502/solr",
[junit4] 2> "node_name":"127.0.0.1:63502_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 119276 INFO (OverseerStateUpdate-72184456082948106-127.0.0.1:63500_solr-n_0000000000) [n:127.0.0.1:63500_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:63500/solr",
[junit4] 2> "node_name":"127.0.0.1:63500_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 119481 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119481 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119503 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 119503 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 119520 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 119524 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 119661 WARN (qtp1321273289-1042) [n:127.0.0.1:63500_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 multiople copyFields (2)
[junit4] 2> 119661 WARN (qtp1321273289-1042) [n:127.0.0.1:63500_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 multiople copyFields (3)
[junit4] 2> 119661 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119661 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 119662 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63500.solr.core.collection1.shard2.replica_n2' (registry 'solr.core.collection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 119662 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node1\collection1_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node1\collection1_shard2_replica_n2\data\]
[junit4] 2> 119664 WARN (qtp972587873-1043) [n:127.0.0.1:63502_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 multiople copyFields (2)
[junit4] 2> 119664 WARN (qtp972587873-1043) [n:127.0.0.1:63502_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 multiople copyFields (3)
[junit4] 2> 119664 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119664 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 119665 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63502.solr.core.collection1.shard1.replica_n1' (registry 'solr.core.collection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 119665 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node3\collection1_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node3\collection1_shard1_replica_n1\data\]
[junit4] 2> 119812 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119812 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119812 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119812 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119817 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 119817 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 119817 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 119817 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 119823 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119823 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119823 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 119825 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119825 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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 1671844193171931136
[junit4] 2> 119826 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119826 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 119826 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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 1671844193172979712
[junit4] 2> 119829 INFO (searcherExecutor-536-thread-1-processing-n:127.0.0.1:63500_solr x:collection1_shard2_replica_n2 c:collection1 s:shard2 r:core_node4) [n:127.0.0.1:63500_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 autowarm time: 0 ms
[junit4] 2> 119833 INFO (searcherExecutor-538-thread-1-processing-n:127.0.0.1:63502_solr x:collection1_shard1_replica_n1 c:collection1 s:shard1 r:core_node3) [n:127.0.0.1:63502_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 autowarm time: 0 ms
[junit4] 2> 119838 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119839 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119842 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119842 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119849 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119849 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119849 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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:63500/solr/collection1_shard2_replica_n2/
[junit4] 2> 119850 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119851 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:63500/solr/collection1_shard2_replica_n2/ has no replicas
[junit4] 2> 119852 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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/72184456082948106-core_node4-n_0000000000
[junit4] 2> 119852 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119852 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119852 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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:63502/solr/collection1_shard1_replica_n1/
[junit4] 2> 119852 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119854 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:63502/solr/collection1_shard1_replica_n1/ has no replicas
[junit4] 2> 119854 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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/72184456082948110-core_node3-n_0000000000
[junit4] 2> 119856 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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:63500/solr/collection1_shard2_replica_n2/ shard2
[junit4] 2> 119856 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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:63502/solr/collection1_shard1_replica_n1/ shard1
[junit4] 2> 119962 INFO (zkCallback-509-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> 119962 INFO (zkCallback-474-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> 119963 INFO (zkCallback-474-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> 119963 INFO (zkCallback-509-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> 119964 INFO (qtp972587873-1043) [n:127.0.0.1:63502_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> 119964 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_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> 119971 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr ] 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=491
[junit4] 2> 119971 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr ] 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=491
[junit4] 2> 119974 INFO (qtp804577605-1037) [n:127.0.0.1:63499_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> 120069 INFO (zkCallback-474-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> 120069 INFO (zkCallback-474-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> 120069 INFO (zkCallback-474-thread-3) [ ] 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> 120069 INFO (zkCallback-509-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> 120069 INFO (zkCallback-509-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> 120069 INFO (zkCallback-467-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> 120069 INFO (zkCallback-509-thread-3) [ ] 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> 120070 INFO (qtp804577605-1037) [n:127.0.0.1:63499_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=1032
[junit4] 2> 120070 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: collection1
[junit4] 2> 120076 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Wait for recoveries to finish - collection: collection1 failOnTimeout: true timeout (sec):
[junit4] 2> 120078 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.AbstractDistribZkTestBase Recoveries finished - collection: collection1
[junit4] 2> 120105 INFO (TEST-StreamDecoratorTest.testParallelDaemonCommitStream-seed#[D46C8C86A64FBAD6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testParallelDaemonCommitStream
[junit4] 2> 120221 INFO (qtp1321273289-1034) [n:127.0.0.1:63500_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> 120221 INFO (qtp1321273289-1034) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=http://127.0.0.1:63502/solr/collection1_shard1_replica_n1/&wt=javabin&version=2} status=0 QTime=3
[junit4] 2> 120235 INFO (qtp1321273289-1042) [n:127.0.0.1:63500_solr c:collection1 s:shard2 r:core_node4 x:collection1_shard2_replica_n2 ] o.a.s.c.S.Request [collection1_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=http://127.0.0.1:63502/solr/collection1_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false} status=0 QTime=0
[junit4] 2> 120237 INFO (qtp972587873-1039) [n:127.0.0.1:63502_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> 120237 INFO (qtp972587873-1039) [n:127.0.0.1:63502_solr c:collection1 s:shard1 r:core_node3 x:collection1_shard1_replica_n1 ] o.a.s.c.S.Request [collection1_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=collection1:4&commit=true&wt=javabin&version=2} status=0 QTime=130
[junit4] 2> 120238 INFO (qtp804577605-1041) [n:127.0.0.1:63499_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=parallelDestinationCollection1&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 120242 INFO (OverseerThreadFactory-482-thread-2-processing-n:127.0.0.1:63500_solr) [n:127.0.0.1:63500_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection parallelDestinationCollection1
[junit4] 2> 120242 INFO (OverseerCollectionConfigSetProcessor-72184456082948106-127.0.0.1:63500_solr-n_0000000000) [n:127.0.0.1:63500_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> 120355 INFO (qtp804577605-1045) [n:127.0.0.1:63499_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 120360 INFO (qtp216422172-1044) [n:127.0.0.1:63501_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 120362 INFO (qtp1321273289-1046) [n:127.0.0.1:63500_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard2.replica_n2:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 120364 INFO (qtp1321273289-1038) [n:127.0.0.1:63500_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=3
[junit4] 2> 120369 INFO (qtp972587873-1035) [n:127.0.0.1:63502_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={wt=javabin&version=2&key=solr.core.collection1.shard1.replica_n1:INDEX.sizeInBytes} status=0 QTime=0
[junit4] 2> 120375 INFO (qtp972587873-1043) [n:127.0.0.1:63502_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=4
[junit4] 2> 120382 INFO (OverseerStateUpdate-72184456082948106-127.0.0.1:63500_solr-n_0000000000) [n:127.0.0.1:63500_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"parallelDestinationCollection1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"parallelDestinationCollection1_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63501/solr",
[junit4] 2> "node_name":"127.0.0.1:63501_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 120384 INFO (OverseerStateUpdate-72184456082948106-127.0.0.1:63500_solr-n_0000000000) [n:127.0.0.1:63500_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"parallelDestinationCollection1",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"parallelDestinationCollection1_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:63499/solr",
[junit4] 2> "node_name":"127.0.0.1:63499_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 120588 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=parallelDestinationCollection1_shard2_replica_n2&action=CREATE&numShards=2&collection=parallelDestinationCollection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 120588 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=parallelDestinationCollection1_shard1_replica_n1&action=CREATE&numShards=2&collection=parallelDestinationCollection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 120605 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 120605 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.6.0
[junit4] 2> 120621 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 120624 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 120740 WARN (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiople copyFields (2)
[junit4] 2> 120741 WARN (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiople copyFields (3)
[junit4] 2> 120741 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 120741 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'parallelDestinationCollection1_shard1_replica_n1' using configuration from configset conf, trusted=true
[junit4] 2> 120741 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63501.solr.core.parallelDestinationCollection1.shard1.replica_n1' (registry 'solr.core.parallelDestinationCollection1.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 120742 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.SolrCore [[parallelDestinationCollection1_shard1_replica_n1] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node2\parallelDestinationCollection1_shard1_replica_n1], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node2\parallelDestinationCollection1_shard1_replica_n1\data\]
[junit4] 2> 120750 WARN (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Field lowerfilt1and2 is not multivalued and destination for multiople copyFields (2)
[junit4] 2> 120751 WARN (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Field text is not multivalued and destination for multiople copyFields (3)
[junit4] 2> 120751 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema test/1.6 with uniqueid field id
[junit4] 2> 120751 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'parallelDestinationCollection1_shard2_replica_n2' using configuration from configset conf, trusted=true
[junit4] 2> 120751 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_63499.solr.core.parallelDestinationCollection1.shard2.replica_n2' (registry 'solr.core.parallelDestinationCollection1.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@15e5cc33
[junit4] 2> 120751 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.SolrCore [[parallelDestinationCollection1_shard2_replica_n2] ] Opening new SolrCore at [C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node4\parallelDestinationCollection1_shard2_replica_n2], dataDir=[C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001\tempDir-001\node4\parallelDestinationCollection1_shard2_replica_n2\data\]
[junit4] 2> 120875 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 120875 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 120881 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 120881 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 120887 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 120888 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 120888 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 120888 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1671844194286567424
[junit4] 2> 120892 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 120892 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 120892 INFO (searcherExecutor-548-thread-1-processing-n:127.0.0.1:63501_solr x:parallelDestinationCollection1_shard1_replica_n1 c:parallelDestinationCollection1 s:shard1 r:core_node3) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.SolrCore [parallelDestinationCollection1_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 120894 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 120896 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 120902 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/parallelDestinationCollection1/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 120902 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/parallelDestinationCollection1/leaders/shard1
[junit4] 2> 120910 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 120910 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 120910 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:63501/solr/parallelDestinationCollection1_shard1_replica_n1/
[junit4] 2> 120910 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 120911 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:63501/solr/parallelDestinationCollection1_shard1_replica_n1/ has no replicas
[junit4] 2> 120915 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/parallelDestinationCollection1/leaders/shard1/leader after winning as /collections/parallelDestinationCollection1/leader_elect/shard1/election/72184456082948109-core_node3-n_0000000000
[junit4] 2> 120917 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 120918 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 120918 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 120918 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1671844194318024704
[junit4] 2> 120918 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:63501/solr/parallelDestinationCollection1_shard1_replica_n1/ shard1
[junit4] 2> 120928 INFO (searcherExecutor-550-thread-1-processing-n:127.0.0.1:63499_solr x:parallelDestinationCollection1_shard2_replica_n2 c:parallelDestinationCollection1 s:shard2 r:core_node4) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.SolrCore [parallelDestinationCollection1_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 120932 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/parallelDestinationCollection1/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 120932 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/parallelDestinationCollection1/leaders/shard2
[junit4] 2> 120939 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 120939 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 120939 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:63499/solr/parallelDestinationCollection1_shard2_replica_n2/
[junit4] 2> 120939 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 120939 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.SyncStrategy http://127.0.0.1:63499/solr/parallelDestinationCollection1_shard2_replica_n2/ has no replicas
[junit4] 2> 120939 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/parallelDestinationCollection1/leaders/shard2/leader after winning as /collections/parallelDestinationCollection1/leader_elect/shard2/election/72184456082948105-core_node4-n_0000000000
[junit4] 2> 120943 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:parallelDestinationCollection1 s:shard2 r:core_node4 x:parallelDestinationCollection1_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:63499/solr/parallelDestinationCollection1_shard2_replica_n2/ shard2
[junit4] 2> 121048 INFO (zkCallback-504-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/parallelDestinationCollection1/state.json] for collection [parallelDestinationCollection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 121048 INFO (zkCallback-467-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/parallelDestinationCollection1/state.json] for collection [parallelDestinationCollection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 121049 INFO (zkCallback-504-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/parallelDestinationCollection1/state.json] for collection [parallelDestinationCollection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 121049 INFO (zkCallback-467-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/parallelDestinationCollection1/state.json] for collection [parallelDestinationCollection1] has occurred - updating... (live nodes size: [4])
[junit4] 2> 121050 INFO (qtp216422172-1048) [n:127.0.0.1:63501_solr c:parallelDestinationCollection1 s:shard1 r:core_node3 x:parallelDestinationCollection1_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 121051 INFO (qtp804577605-1049) [n:127.0.0.1:63499_solr c:
[...truncated too long message...]
ns/parallelDestinationCollection1/terms/shard1
[junit4] 2> 8 /solr/collections/parallelDestinationCollection1/terms/shard2
[junit4] 2> 8 /solr/collections/destinationCollection/terms/shard2
[junit4] 2> 7 /solr/collections/destination1/state.json
[junit4] 2> 7 /solr/collections/checkpointCollection/state.json
[junit4] 2> 7 /solr/collections/destination/state.json
[junit4] 2> 7 /solr/collections/workQueue1/state.json
[junit4] 2> 7 /solr/collections/parallelDestinationCollection/terms/shard1
[junit4] 2> 7 /solr/collections/destinationCollection/terms/shard1
[junit4] 2> 6 /solr/collections/mainCorpus1/state.json
[junit4] 2> 6 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 6 /solr/collections/parallelDestinationCollection/terms/shard2
[junit4] 2> 5 /solr/collections/mainCorpus/state.json
[junit4] 2> 5 /solr/collections/uknownCollection/state.json
[junit4] 2> 4 /solr/collections/workQueue1/terms/shard2
[junit4] 2> 4 /solr/collections/workQueue1/terms/shard1
[junit4] 2> 4 /solr/collections/destination1/terms/shard1
[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/modelCollection/terms/shard2
[junit4] 2> 4 /solr/collections/modelCollection/terms/shard1
[junit4] 2> 4 /solr/packages.json
[junit4] 2> 4 /solr/configs/ml
[junit4] 2> 4 /solr/collections/destination/terms/shard1
[junit4] 2> 4 /solr/collections/checkpointCollection/terms/shard2
[junit4] 2> 4 /solr/collections/checkpointCollection/terms/shard1
[junit4] 2> 4 /solr/security.json
[junit4] 2> 4 /solr/collections/mainCorpus/terms/shard1
[junit4] 2> 4 /solr/collections/uknownCollection/terms/shard2
[junit4] 2> 4 /solr/collections/uknownCollection/terms/shard1
[junit4] 2> 3 /solr/collections/destination/terms/shard2
[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/workQueue/terms/shard2
[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/checkpointCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/destinationCollection/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue/collectionprops.json
[junit4] 2> 2 /solr/collections/destination/collectionprops.json
[junit4] 2> 2 /solr/collections/parallelDestinationCollection1/collectionprops.json
[junit4] 2> 2 /solr/collections/destination1/collectionprops.json
[junit4] 2> 2 /solr/collections/workQueue1/collectionprops.json
[junit4] 2> 2 /solr/collections/parallelDestinationCollection/collectionprops.json
[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> 69 /solr/collections/parallelDestinationCollection1/state.json
[junit4] 2> 67 /solr/clusterstate.json
[junit4] 2> 40 /solr/collections/parallelDestinationCollection/state.json
[junit4] 2> 40 /solr/collections/destinationCollection/state.json
[junit4] 2> 26 /solr/collections/workQueue/state.json
[junit4] 2> 26 /solr/collections/modelCollection/state.json
[junit4] 2> 25 /solr/collections/collection1/state.json
[junit4] 2> 25 /solr/collections/uknownCollection/state.json
[junit4] 2> 20 /solr/collections/destination/state.json
[junit4] 2> 20 /solr/collections/destination1/state.json
[junit4] 2> 20 /solr/collections/workQueue1/state.json
[junit4] 2> 20 /solr/collections/checkpointCollection/state.json
[junit4] 2> 20 /solr/collections/mainCorpus/state.json
[junit4] 2> 19 /solr/collections/mainCorpus1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 337 /solr/collections
[junit4] 2> 284 /solr/overseer/queue
[junit4] 2> 107 /solr/overseer/collection-queue-work
[junit4] 2> 80 /solr/live_nodes
[junit4] 2>
[junit4] 2> 216675 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:63470
[junit4] 2> 216675 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:63470
[junit4] 2> 216675 INFO (SUITE-StreamDecoratorTest-seed#[D46C8C86A64FBAD6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 63470
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-solrj\test\J2\temp\solr.client.solrj.io.stream.StreamDecoratorTest_D46C8C86A64FBAD6-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {expr_s=PostingsFormat(name=LuceneVarGapFixedInterval), name_s=PostingsFormat(name=LuceneFixedGap), terms_ss=PostingsFormat(name=LuceneVarGapDocFreqInterval), join2_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), field_s=Lucene84, multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), subject=PostingsFormat(name=LuceneVarGapDocFreqInterval), a1_s=Lucene84, a2_s=PostingsFormat(name=LuceneVarGapFixedInterval), body_t=PostingsFormat(name=LuceneFixedGap), s_multi=PostingsFormat(name=LuceneVarGapFixedInterval), a_ss=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_s=PostingsFormat(name=LuceneVarGapFixedInterval), tv_text=Lucene84, text_s=PostingsFormat(name=LuceneVarGapFixedInterval), id=PostingsFormat(name=LuceneFixedGap), text=PostingsFormat(name=LuceneVarGapFixedInterval), bbid_s=PostingsFormat(name=LuceneFixedGap), type_s=Lucene84, checkpoint_ss=PostingsFormat(name=LuceneFixedGap), test_t=PostingsFormat(name=LuceneFixedGap), ident_s=Lucene84, extra_s=PostingsFormat(name=LuceneVarGapDocFreqInterval), ykey_s=Lucene84, side_s=PostingsFormat(name=LuceneFixedGap), deletable_s=PostingsFormat(name=LuceneVarGapDocFreqInterval)}, docValues:{expr_s=DocValuesFormat(name=Lucene80), name_s=DocValuesFormat(name=Lucene80), iteration_i=DocValuesFormat(name=Lucene80), terms_ss=DocValuesFormat(name=Direct), join2_s=DocValuesFormat(name=Direct), field_s=DocValuesFormat(name=Asserting), multiDefault=DocValuesFormat(name=Direct), a1_s=DocValuesFormat(name=Asserting), a_f=DocValuesFormat(name=Asserting), intDefault=DocValuesFormat(name=Asserting), join1_i=DocValuesFormat(name=Lucene80), falseNegative_i=DocValuesFormat(name=Asserting), a2_s=DocValuesFormat(name=Lucene80), a_i=DocValuesFormat(name=Lucene80), join3_i=DocValuesFormat(name=Lucene80), test_i=DocValuesFormat(name=Direct), falsePositive_i=DocValuesFormat(name=Asserting), field_i=DocValuesFormat(name=Direct), s_multi=DocValuesFormat(name=Lucene80), a_ss=DocValuesFormat(name=Direct), a_s=DocValuesFormat(name=Lucene80), text_s=DocValuesFormat(name=Lucene80), id=DocValuesFormat(name=Lucene80), bbid_s=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Direct), type_s=DocValuesFormat(name=Asserting), i_multi=DocValuesFormat(name=Lucene80), trueNegative_i=DocValuesFormat(name=Lucene80), b_ls=DocValuesFormat(name=Asserting), out_i=DocValuesFormat(name=Asserting), checkpoint_ss=DocValuesFormat(name=Lucene80), ident_s=DocValuesFormat(name=Asserting), _version_=DocValuesFormat(name=Asserting), extra_s=DocValuesFormat(name=Direct), ykey_s=DocValuesFormat(name=Asserting), side_s=DocValuesFormat(name=Lucene80), truePositive_i=DocValuesFormat(name=Lucene80), deletable_s=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=46, maxMBSortInHeap=7.447457979016374, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=no-NO, timezone=America/Mazatlan
[junit4] 2> NOTE: Windows 10 10.0 amd64/AdoptOpenJDK 14.0.1 (64-bit)/cpus=6,threads=1,free=208324424,total=526385152
[junit4] 2> NOTE: All tests run in this JVM: [TestDelegationTokenResponse, TestObjectBuilder, SolrZkClientTest, ArcSineEvaluatorTest, TestPolicyOld, UsingSolrJRefGuideExamplesTest, TestXMLEscaping, TermsFacetMapTest, SolrExampleBinaryTest, SolrPingTest, TestBatchUpdate, CloudSolrClientRetryTest, StreamDecoratorTest]
[junit4] Completed [123/208 (1!)] on J2 in 101.23s, 52 tests, 1 error <<< FAILURES!
[...truncated 43931 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.6-Windows/353/consoleText
[repro] Revision: afeaa52f625707d3f653f9def76d32c027eb19a1
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC"
[repro] JUnit rest result XML files will be moved to: ./repro-reports
[repro] ant clean
[...truncated 6 lines...]
[repro] Test suites by module:
[repro] solr\solrj
[repro] StreamDecoratorTest
[repro] ant compile-test
[...truncated 2606 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.StreamDecoratorTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseShenandoahGC" -Dtests.seed=D46C8C86A64FBAD6 -Dtests.slow=true -Dtests.locale=no-NO -Dtests.timezone=America/Mazatlan -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[...truncated 337 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.client.solrj.io.stream.StreamDecoratorTest
[repro] Exiting with code 0
[...truncated 96 lines...]
[JENKINS] Lucene-Solr-8.6-Windows (64bit/jdk1.8.0_201) - Build #
354 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.6-Windows/354/
Java: 64bit/jdk1.8.0_201 -XX:+UseCompressedOops -XX:+UseParallelGC
2 tests failed.
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testIndexing
Error Message:
OverseerTriggerThread never caught up to the latest znodeVersion
Stack Trace:
java.util.concurrent.TimeoutException: OverseerTriggerThread never caught up to the latest znodeVersion
at __randomizedtesting.SeedInfo.seed([490479DAEFF69BC3:ADDE36D3C983D848]:0)
at org.apache.solr.util.TimeOut.waitFor(TimeOut.java:66)
at org.apache.solr.cloud.autoscaling.sim.SimScenario$LoadAutoscaling.execute(SimScenario.java:467)
at org.apache.solr.cloud.autoscaling.sim.SimScenario.run(SimScenario.java:1117)
at org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testIndexing(TestSimScenario.java:143)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testIndexing
Error Message:
OverseerTriggerThread never caught up to the latest znodeVersion
Stack Trace:
java.util.concurrent.TimeoutException: OverseerTriggerThread never caught up to the latest znodeVersion
at __randomizedtesting.SeedInfo.seed([490479DAEFF69BC3:ADDE36D3C983D848]:0)
at org.apache.solr.util.TimeOut.waitFor(TimeOut.java:66)
at org.apache.solr.cloud.autoscaling.sim.SimScenario$LoadAutoscaling.execute(SimScenario.java:467)
at org.apache.solr.cloud.autoscaling.sim.SimScenario.run(SimScenario.java:1117)
at org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testIndexing(TestSimScenario.java:143)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 13423 lines...]
[junit4] Suite: org.apache.solr.cloud.autoscaling.sim.TestSimScenario
[junit4] 2> 17208 INFO (SUITE-TestSimScenario-seed#[490479DAEFF69BC3]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of 'C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\server\solr\configsets\_default\conf'
[junit4] 2> 17294 INFO (SUITE-TestSimScenario-seed#[490479DAEFF69BC3]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 17316 INFO (SUITE-TestSimScenario-seed#[490479DAEFF69BC3]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.TestSimScenario_490479DAEFF69BC3-001\data-dir-1-001
[junit4] 2> 18032 INFO (SUITE-TestSimScenario-seed#[490479DAEFF69BC3]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 18488 INFO (SUITE-TestSimScenario-seed#[490479DAEFF69BC3]-worker) [ ] o.e.j.u.log Logging initialized @18512ms to org.eclipse.jetty.util.log.Slf4jLog
[junit4] 2> 18520 INFO (SUITE-TestSimScenario-seed#[490479DAEFF69BC3]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 18937 INFO (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testIndexing
[junit4] 2> 19166 INFO (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 1. CreateCluster numNodes=100
[junit4] 2> 19181 INFO (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario CreateCluster numNodes=100
[junit4] 2> 19566 INFO (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.SolrPaths solr home defaulted to 'solr/' (could not find system property or JNDI)
[junit4] 2> 20560 DEBUG (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10000_solr
[junit4] 2> 20672 DEBUG (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 20677 DEBUG (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 20677 INFO (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 20718 INFO (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 2. LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 20720 INFO (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 20788 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 20832 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 22267 DEBUG (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 22267 DEBUG (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 22267 DEBUG (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 22267 DEBUG (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 22267 DEBUG (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 22267 DEBUG (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 22264 WARN (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Interrupted
[junit4] 2> => java.lang.InterruptedException
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220)
[junit4] 2> java.lang.InterruptedException: null
[junit4] 2> at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireInterruptibly(AbstractQueuedSynchronizer.java:1220) ~[?:1.8.0_201]
[junit4] 2> at java.util.concurrent.locks.ReentrantLock.lockInterruptibly(ReentrantLock.java:335) ~[?:1.8.0_201]
[junit4] 2> at org.apache.solr.cloud.autoscaling.OverseerTriggerThread.run(OverseerTriggerThread.java:197) [java/:?]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_201]
[junit4] 2> 22326 INFO (TEST-TestSimScenario.testIndexing-seed#[490479DAEFF69BC3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testIndexing
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSimScenario -Dtests.method=testIndexing -Dtests.seed=490479DAEFF69BC3 -Dtests.slow=true -Dtests.locale=es-AR -Dtests.timezone=Australia/Adelaide -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] ERROR 3.63s J1 | TestSimScenario.testIndexing <<<
[junit4] > Throwable #1: java.util.concurrent.TimeoutException: OverseerTriggerThread never caught up to the latest znodeVersion
[junit4] > at __randomizedtesting.SeedInfo.seed([490479DAEFF69BC3:ADDE36D3C983D848]:0)
[junit4] > at org.apache.solr.util.TimeOut.waitFor(TimeOut.java:66)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.SimScenario$LoadAutoscaling.execute(SimScenario.java:467)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.SimScenario.run(SimScenario.java:1117)
[junit4] > at org.apache.solr.cloud.autoscaling.sim.TestSimScenario.testIndexing(TestSimScenario.java:143)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 22468 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testSuggestions
[junit4] 2> 22471 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 1. CreateCluster numNodes=2
[junit4] 2> 22471 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario CreateCluster numNodes=2
[junit4] 2> 22513 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10100_solr
[junit4] 2> 22513 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 22513 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 22513 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 22514 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 2. LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 22514 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 22520 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 22524 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 22525 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 22526 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 22563 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10100_solr, 127.0.0.1:10101_solr]
[junit4] 2> 22563 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 22563 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 22569 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 3. RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 22569 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 22617 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22644 DEBUG (ScheduledTrigger-10-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22663 DEBUG (simCloudManagerPool-9-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testCollection, currentVersion=1
[junit4] 2> 22675 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22693 DEBUG (simCloudManagerPool-9-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 22696 DEBUG (simCloudManagerPool-9-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 22706 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22739 DEBUG (ScheduledTrigger-10-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22770 DEBUG (simCloudManagerPool-9-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 22770 DEBUG (ScheduledTrigger-10-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22782 DEBUG (simCloudManagerPool-9-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 22800 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22831 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22862 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22893 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22924 DEBUG (ScheduledTrigger-10-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22955 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 22987 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23019 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23054 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23084 DEBUG (ScheduledTrigger-10-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23115 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23146 DEBUG (ScheduledTrigger-10-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23148 DEBUG (simCloudManagerPool-9-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 23148 DEBUG (simCloudManagerPool-9-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 23148 DEBUG (simCloudManagerPool-9-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard1)
[junit4] 2> 23157 DEBUG (simCloudManagerPool-9-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard1 (currentVersion=4): {"core_node1":{
[junit4] 2> "core":"testCollection_shard1_replica_n1",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10101_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 23178 DEBUG (ScheduledTrigger-10-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23178 DEBUG (simCloudManagerPool-9-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 23178 DEBUG (simCloudManagerPool-9-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 23178 DEBUG (simCloudManagerPool-9-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard2)
[junit4] 2> 23178 DEBUG (simCloudManagerPool-9-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard2 (currentVersion=5): {"core_node3":{
[junit4] 2> "core":"testCollection_shard2_replica_n3",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10101_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 23194 DEBUG (simCloudManagerPool-9-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 23195 DEBUG (simCloudManagerPool-9-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 23196 DEBUG (simCloudManagerPool-9-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testCollection, currentVersion=6
[junit4] 2> 23196 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 4. WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 23196 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 23210 DEBUG (simCloudManagerPool-9-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 23210 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23212 DEBUG (simCloudManagerPool-9-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 23213 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 23215 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 23215 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 5. CtxSet key=myNode&value=${_random_node_}
[junit4] 2> 23221 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario CtxSet key=myNode&value=127.0.0.1:10100_solr
[junit4] 2> 23221 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 6. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 23221 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10100_solr
[junit4] 2> 23242 DEBUG (ScheduledTrigger-10-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23244 INFO (simCloudManagerPool-9-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10100_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 23244 INFO (simCloudManagerPool-9-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 23274 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23274 DEBUG (simCloudManagerPool-9-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 23275 DEBUG (simCloudManagerPool-9-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 23275 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 7. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 23276 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10100_solr
[junit4] 2> 23277 DEBUG (simCloudManagerPool-9-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 23278 DEBUG (simCloudManagerPool-9-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 23279 INFO (simCloudManagerPool-9-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10100_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 23283 INFO (simCloudManagerPool-9-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 23306 DEBUG (ScheduledTrigger-10-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23306 DEBUG (simCloudManagerPool-9-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 23307 DEBUG (simCloudManagerPool-9-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 23308 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 8. LoopOp iterations=${iterative}
[junit4] 2> 23308 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario LoopOp iterations=0
[junit4] 2> 23308 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 9. LoopOp iterations=${justCalc}
[junit4] 2> 23309 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario LoopOp iterations=1
[junit4] 2> 23309 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario * iter 1 :
[junit4] 2> 23309 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 23321 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 23323 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 23336 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23371 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23403 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23436 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23465 DEBUG (ScheduledTrigger-10-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23496 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23534 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23545 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 23545 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - SaveSnapshot path=C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-core\test\J1\temp\solr.cloud.autoscaling.sim.TestSimScenario_490479DAEFF69BC3-001\tempDir-001/snapshot/0)
[junit4] 2> 23559 DEBUG (ScheduledTrigger-10-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23576 WARN (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimUtils V2 action SET_CLUSTER_PROPERTY_OBJ has no equivalent V1 action
[junit4] 2> 23576 WARN (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimUtils V2 action GET_NODES has no equivalent V1 action
[junit4] 2> 23578 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - copying 22 resources from SimDistribStateManager
[junit4] 2> 23606 DEBUG (ScheduledTrigger-10-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23637 DEBUG (ScheduledTrigger-10-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23668 DEBUG (ScheduledTrigger-10-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23675 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 10. Dump redact=true
[junit4] 2> 23675 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario Dump redact=true
[junit4] 2> 23676 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - copying 22 resources from SimDistribStateManager
[junit4] 2> 23691 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 23691 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 23691 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 23691 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 23691 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 23691 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 23691 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 23694 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 1. CreateCluster numNodes=2
[junit4] 2> 23695 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario CreateCluster numNodes=2
[junit4] 2> 23726 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider --- new Overseer leader: 127.0.0.1:10102_solr
[junit4] 2> 23726 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=0
[junit4] 2> 23726 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 0
[junit4] 2> 23726 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 23727 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 2. LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 23727 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 23727 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario LoadAutoscaling json={'cluster-policy':[]}
[junit4] 2> 23728 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 23730 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 23730 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 23731 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:10102_solr, 127.0.0.1:10103_solr]
[junit4] 2> 23731 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 23731 DEBUG (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 23733 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 3. RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 23733 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=CREATE&autoAddReplicas=true&name=testCollection&numShards=2&replicationFactor=2&maxShardsPerNode=2
[junit4] 2> 23734 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23734 DEBUG (simCloudManagerPool-15-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- simCreateCollection testCollection, currentVersion=1
[junit4] 2> 23735 DEBUG (simCloudManagerPool-15-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=1
[junit4] 2> 23735 DEBUG (simCloudManagerPool-15-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 1
[junit4] 2> 23747 DEBUG (simCloudManagerPool-15-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=2
[junit4] 2> 23747 DEBUG (simCloudManagerPool-15-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 2
[junit4] 2> 23762 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23762 DEBUG (simCloudManagerPool-15-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=3
[junit4] 2> 23762 DEBUG (simCloudManagerPool-15-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 3
[junit4] 2> 23764 DEBUG (simCloudManagerPool-15-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard1)
[junit4] 2> 23764 DEBUG (simCloudManagerPool-15-thread-3) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard1 (currentVersion=4): {"core_node2":{
[junit4] 2> "core":"testCollection_shard1_replica_n2",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10102_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 23778 DEBUG (simCloudManagerPool-15-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=4
[junit4] 2> 23779 DEBUG (simCloudManagerPool-15-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 4
[junit4] 2> 23779 DEBUG (simCloudManagerPool-15-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider Running leader election (testCollection / shard2)
[junit4] 2> 23779 DEBUG (simCloudManagerPool-15-thread-4) [ ] o.a.s.c.a.s.SimClusterStateProvider -- elected new leader for testCollection / shard2 (currentVersion=5): {"core_node3":{
[junit4] 2> "core":"testCollection_shard2_replica_n3",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10103_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 23779 DEBUG (simCloudManagerPool-15-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider -- finished createCollection testCollection, currentVersion=5
[junit4] 2> 23780 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 4. WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 23781 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 23795 DEBUG (simCloudManagerPool-15-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=5
[junit4] 2> 23795 DEBUG (simCloudManagerPool-15-thread-2) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 5
[junit4] 2> 23796 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23812 DEBUG (simCloudManagerPool-15-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=6
[junit4] 2> 23813 DEBUG (simCloudManagerPool-15-thread-5) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 6
[junit4] 2> 23814 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=7
[junit4] 2> 23814 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 7
[junit4] 2> 23814 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 5. CtxSet key=myNode&value=${_random_node_}
[junit4] 2> 23814 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario CtxSet key=myNode&value=127.0.0.1:10102_solr
[junit4] 2> 23814 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 6. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 23814 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr
[junit4] 2> 23816 INFO (simCloudManagerPool-15-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 23816 INFO (simCloudManagerPool-15-thread-6) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 23827 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23827 DEBUG (simCloudManagerPool-15-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=8
[junit4] 2> 23829 DEBUG (simCloudManagerPool-15-thread-6) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 8
[junit4] 2> 23829 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 7. RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=${myNode}
[junit4] 2> 23829 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr
[junit4] 2> 23832 DEBUG (simCloudManagerPool-15-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=9
[junit4] 2> 23833 DEBUG (simCloudManagerPool-15-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 9
[junit4] 2> 23833 INFO (simCloudManagerPool-15-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 23834 INFO (simCloudManagerPool-15-thread-7) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 23859 DEBUG (simCloudManagerPool-15-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=10
[junit4] 2> 23859 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23860 DEBUG (simCloudManagerPool-15-thread-7) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 10
[junit4] 2> 23860 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 8. LoopOp iterations=${iterative}
[junit4] 2> 23860 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario LoopOp iterations=10
[junit4] 2> 23860 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario * iter 1 :
[junit4] 2> 23860 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 23860 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=11
[junit4] 2> 23861 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 11
[junit4] 2> 23863 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 23863 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 23890 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23924 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23960 DEBUG (ScheduledTrigger-16-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 23987 DEBUG (simCloudManagerPool-15-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node10":{
[junit4] 2> "core":"testCollection_shard2_replica_n9",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10103_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 24023 DEBUG (ScheduledTrigger-16-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24053 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24084 DEBUG (ScheduledTrigger-16-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24115 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24149 DEBUG (simCloudManagerPool-15-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=12
[junit4] 2> 24151 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24153 DEBUG (simCloudManagerPool-15-thread-8) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 12
[junit4] 2> 24153 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 24165 DEBUG (simCloudManagerPool-15-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=13
[junit4] 2> 24167 DEBUG (simCloudManagerPool-15-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 13
[junit4] 2> 24168 INFO (simCloudManagerPool-15-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 24168 INFO (simCloudManagerPool-15-thread-9) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 24192 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24195 DEBUG (simCloudManagerPool-15-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=14
[junit4] 2> 24197 DEBUG (simCloudManagerPool-15-thread-9) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 14
[junit4] 2> 24197 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 24197 DEBUG (simCloudManagerPool-15-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=15
[junit4] 2> 24200 DEBUG (simCloudManagerPool-15-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 15
[junit4] 2> 24200 INFO (simCloudManagerPool-15-thread-10) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 24202 INFO (simCloudManagerPool-15-thread-10) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 24229 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24230 DEBUG (simCloudManagerPool-15-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=16
[junit4] 2> 24232 DEBUG (simCloudManagerPool-15-thread-10) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 16
[junit4] 2> 24232 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario * iter 2 :
[junit4] 2> 24232 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 24232 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=17
[junit4] 2> 24232 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 17
[junit4] 2> 24232 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 24232 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 24260 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24291 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24323 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24338 DEBUG (simCloudManagerPool-15-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node16":{
[junit4] 2> "core":"testCollection_shard1_replica_n15",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10103_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "leader":"true",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 24354 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24385 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24419 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24450 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24466 DEBUG (simCloudManagerPool-15-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=18
[junit4] 2> 24467 DEBUG (simCloudManagerPool-15-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 18
[junit4] 2> 24467 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 24467 DEBUG (simCloudManagerPool-15-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=19
[junit4] 2> 24469 DEBUG (simCloudManagerPool-15-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 19
[junit4] 2> 24470 INFO (simCloudManagerPool-15-thread-12) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 24470 INFO (simCloudManagerPool-15-thread-12) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 24484 DEBUG (simCloudManagerPool-15-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=20
[junit4] 2> 24486 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24487 DEBUG (simCloudManagerPool-15-thread-12) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 20
[junit4] 2> 24487 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 24488 DEBUG (simCloudManagerPool-15-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=21
[junit4] 2> 24488 DEBUG (simCloudManagerPool-15-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 21
[junit4] 2> 24488 INFO (simCloudManagerPool-15-thread-13) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 24488 INFO (simCloudManagerPool-15-thread-13) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 24499 DEBUG (simCloudManagerPool-15-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=22
[junit4] 2> 24499 DEBUG (simCloudManagerPool-15-thread-13) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 22
[junit4] 2> 24499 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario * iter 3 :
[junit4] 2> 24502 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 24502 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=23
[junit4] 2> 24503 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 23
[junit4] 2> 24505 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 24506 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 24515 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24547 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24578 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24612 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24613 DEBUG (simCloudManagerPool-15-thread-14) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node22":{
[junit4] 2> "core":"testCollection_shard1_replica_n21",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10103_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "node":"127.0.0.1:10102_solr",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10102_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 24642 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24673 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24706 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24737 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24737 DEBUG (simCloudManagerPool-15-thread-14) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=24
[junit4] 2> 24737 DEBUG (simCloudManagerPool-15-thread-14) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 24
[junit4] 2> 24738 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 24739 DEBUG (simCloudManagerPool-15-thread-15) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=25
[junit4] 2> 24739 DEBUG (simCloudManagerPool-15-thread-15) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 25
[junit4] 2> 24739 INFO (simCloudManagerPool-15-thread-15) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 24740 INFO (simCloudManagerPool-15-thread-15) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 24753 DEBUG (simCloudManagerPool-15-thread-15) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=26
[junit4] 2> 24753 DEBUG (simCloudManagerPool-15-thread-15) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 26
[junit4] 2> 24754 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 24754 DEBUG (simCloudManagerPool-15-thread-16) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=27
[junit4] 2> 24755 DEBUG (simCloudManagerPool-15-thread-16) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 27
[junit4] 2> 24755 INFO (simCloudManagerPool-15-thread-16) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 24756 INFO (simCloudManagerPool-15-thread-16) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 24769 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24769 DEBUG (simCloudManagerPool-15-thread-16) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=28
[junit4] 2> 24770 DEBUG (simCloudManagerPool-15-thread-16) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 28
[junit4] 2> 24770 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario * iter 4 :
[junit4] 2> 24770 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 24770 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=29
[junit4] 2> 24771 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 29
[junit4] 2> 24771 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 24772 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 24801 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24833 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24867 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24882 DEBUG (simCloudManagerPool-15-thread-17) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node28":{
[junit4] 2> "core":"testCollection_shard1_replica_n27",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10103_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "node":"127.0.0.1:10102_solr",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10102_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 24898 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24932 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24963 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 24997 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25012 DEBUG (simCloudManagerPool-15-thread-17) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=30
[junit4] 2> 25012 DEBUG (simCloudManagerPool-15-thread-17) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 30
[junit4] 2> 25012 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 25012 DEBUG (MetricsHistoryHandler-19-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=31
[junit4] 2> 25012 DEBUG (MetricsHistoryHandler-19-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 31
[junit4] 2> 25015 INFO (simCloudManagerPool-15-thread-18) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 25015 INFO (simCloudManagerPool-15-thread-18) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 25028 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25032 DEBUG (simCloudManagerPool-15-thread-18) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=32
[junit4] 2> 25034 DEBUG (simCloudManagerPool-15-thread-18) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 32
[junit4] 2> 25035 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 25036 DEBUG (simCloudManagerPool-15-thread-19) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=33
[junit4] 2> 25038 DEBUG (simCloudManagerPool-15-thread-19) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 33
[junit4] 2> 25038 INFO (simCloudManagerPool-15-thread-19) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 25039 INFO (simCloudManagerPool-15-thread-19) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 25059 DEBUG (simCloudManagerPool-15-thread-19) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=34
[junit4] 2> 25059 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25060 DEBUG (simCloudManagerPool-15-thread-19) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 34
[junit4] 2> 25060 DEBUG (MetricsHistoryHandler-19-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=35
[junit4] 2> 25065 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario * iter 5 :
[junit4] 2> 25065 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 25067 DEBUG (MetricsHistoryHandler-19-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 35
[junit4] 2> 25071 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 25071 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 25091 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25123 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25153 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25188 DEBUG (simCloudManagerPool-15-thread-20) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node34":{
[junit4] 2> "core":"testCollection_shard1_replica_n33",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10103_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "node":"127.0.0.1:10102_solr",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10102_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 25189 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25219 DEBUG (ScheduledTrigger-16-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25250 DEBUG (ScheduledTrigger-16-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25282 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25315 DEBUG (simCloudManagerPool-15-thread-20) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=36
[junit4] 2> 25315 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25318 DEBUG (simCloudManagerPool-15-thread-20) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 36
[junit4] 2> 25319 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 25322 DEBUG (simCloudManagerPool-15-thread-21) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=37
[junit4] 2> 25326 DEBUG (simCloudManagerPool-15-thread-21) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 37
[junit4] 2> 25327 INFO (simCloudManagerPool-15-thread-21) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 25327 INFO (simCloudManagerPool-15-thread-21) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 25347 DEBUG (simCloudManagerPool-15-thread-21) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=38
[junit4] 2> 25348 DEBUG (simCloudManagerPool-15-thread-21) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 38
[junit4] 2> 25353 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25353 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 25357 DEBUG (simCloudManagerPool-15-thread-22) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=39
[junit4] 2> 25358 DEBUG (simCloudManagerPool-15-thread-22) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 39
[junit4] 2> 25359 INFO (simCloudManagerPool-15-thread-22) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 25359 INFO (simCloudManagerPool-15-thread-22) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 25379 DEBUG (ScheduledTrigger-16-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25379 DEBUG (simCloudManagerPool-15-thread-22) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=40
[junit4] 2> 25380 DEBUG (simCloudManagerPool-15-thread-22) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 40
[junit4] 2> 25380 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario * iter 6 :
[junit4] 2> 25380 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 25380 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=41
[junit4] 2> 25382 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 41
[junit4] 2> 25384 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 25384 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 25410 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25441 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25476 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25492 DEBUG (simCloudManagerPool-15-thread-23) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node40":{
[junit4] 2> "core":"testCollection_shard1_replica_n39",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10103_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "node":"127.0.0.1:10102_solr",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10102_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 25507 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25538 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25570 DEBUG (ScheduledTrigger-16-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25603 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25619 DEBUG (simCloudManagerPool-15-thread-23) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=42
[junit4] 2> 25620 DEBUG (simCloudManagerPool-15-thread-23) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 42
[junit4] 2> 25620 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 25620 DEBUG (simCloudManagerPool-15-thread-24) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=43
[junit4] 2> 25621 DEBUG (simCloudManagerPool-15-thread-24) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 43
[junit4] 2> 25621 INFO (simCloudManagerPool-15-thread-24) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 25622 INFO (simCloudManagerPool-15-thread-24) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 25634 DEBUG (ScheduledTrigger-16-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25635 DEBUG (simCloudManagerPool-15-thread-24) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=44
[junit4] 2> 25635 DEBUG (simCloudManagerPool-15-thread-24) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 44
[junit4] 2> 25636 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 25637 DEBUG (simCloudManagerPool-15-thread-25) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=45
[junit4] 2> 25637 DEBUG (simCloudManagerPool-15-thread-25) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 45
[junit4] 2> 25637 INFO (simCloudManagerPool-15-thread-25) [ ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:10102_solr for creating new replica of shard shard1 for collection testCollection
[junit4] 2> 25638 INFO (simCloudManagerPool-15-thread-25) [ ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 25650 DEBUG (simCloudManagerPool-15-thread-25) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=46
[junit4] 2> 25650 DEBUG (simCloudManagerPool-15-thread-25) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 46
[junit4] 2> 25650 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario * iter 7 :
[junit4] 2> 25651 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - CalculateSuggestions )
[junit4] 2> 25651 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=47
[junit4] 2> 25651 DEBUG (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 47
[junit4] 2> 25652 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - 1 suggestions
[junit4] 2> 25652 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - ApplySuggestions )
[junit4] 2> 25665 DEBUG (ScheduledTrigger-16-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25697 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25729 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25764 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25764 DEBUG (simCloudManagerPool-15-thread-26) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node46":{
[junit4] 2> "core":"testCollection_shard1_replica_n45",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10103_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "node":"127.0.0.1:10102_solr",
[junit4] 2> "path":"/admin/collections",
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "action":"ADDREPLICA",
[junit4] 2> "createNodeSet":"127.0.0.1:10102_solr",
[junit4] 2> "state":"active",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6}}
[junit4] 2> 25796 DEBUG (ScheduledTrigger-16-thread-1) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25828 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25859 DEBUG (ScheduledTrigger-16-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25891 DEBUG (simCloudManagerPool-15-thread-26) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=48
[junit4] 2> 25891 DEBUG (ScheduledTrigger-16-thread-4) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 25891 DEBUG (simCloudManagerPool-15-thread-26) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 48
[junit4] 2> 25892 INFO (TEST-TestSimScenario.testSuggestions-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario - RunSolrRequest path=/admin/collections&action=ADDREPLICA&collection=testCollection&shard=shard1&node=127.0.0.1:10102_solr)
[junit4] 2> 25892 DEBUG (simCloudManagerPool-15-thread-27) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=49
[junit4] 2> 25893 DEBUG (simCloudManagerPool-15-thread-27) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 49
[junit4] 2> 25893 INFO (si
[...truncated too long message...]
3-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 13
[junit4] 2> 26611 DEBUG (simCloudManagerPool-33-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider -- new replica: {"core_node8":{
[junit4] 2> "core":"testCollection_shard2_replica_n7",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"testCollection",
[junit4] 2> "node_name":"127.0.0.1:10109_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "SEARCHER.searcher.maxDoc":0,
[junit4] 2> "INDEX.sizeInBytes":10240,
[junit4] 2> "SEARCHER.searcher.deletedDocs":0,
[junit4] 2> "state":"down",
[junit4] 2> "INDEX.sizeInGB":9.5367431640625E-6,
[junit4] 2> "SEARCHER.searcher.numDocs":0}}
[junit4] 2> 26735 DEBUG (simCloudManagerPool-33-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=14
[junit4] 2> 26735 DEBUG (simCloudManagerPool-33-thread-11) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 14
[junit4] 2> 26737 DEBUG (AutoscalingActionExecutor-35-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** creating new collection states, currentVersion=15
[junit4] 2> 26738 DEBUG (AutoscalingActionExecutor-35-thread-1) [ ] o.a.s.c.a.s.SimClusterStateProvider ** saved cluster state version 15
[junit4] 2> 26738 DEBUG (AutoscalingActionExecutor-35-thread-1) [ ] o.a.s.c.a.SystemLogListener Collection .system missing, skip sending event {
[junit4] 2> "id":"6f6125c93a45cTbjydgzxdt1g1x9x4f9j9ddsr8",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":1959408583287900,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1959408583287900],
[junit4] 2> "preferredOperation":"movereplica",
[junit4] 2> "_enqueue_time_":1959418945282900,
[junit4] 2> "nodeNames":["127.0.0.1:10108_solr"]}}
[junit4] 2> 26747 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 8. WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 26749 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario WaitCollection collection=testCollection&shards=2&replicas=2
[junit4] 2> 26749 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario 9. SaveSnapshot path=${snapshotPath}
[junit4] 2> 26749 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SimScenario SaveSnapshot path=C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.sim.TestSimScenario_490479DAEFF69BC3-001\tempDir-002/snapshot
[junit4] 2> 26749 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - copying 23 resources from SimDistribStateManager
[junit4] 2> 26753 DEBUG (AutoscalingActionExecutor-35-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .scheduled_maintenance after 100ms
[junit4] 2> 26753 DEBUG (AutoscalingActionExecutor-35-thread-1) [ ] o.a.s.c.a.ScheduledTriggers Resuming trigger: .auto_add_replicas after 100ms
[junit4] 2> 26753 DEBUG (AutoscalingActionExecutor-35-thread-1) [ ] o.a.s.c.a.ScheduledTriggers -- processing took 26248 ms for event id=6f6125c93a45cTbjydgzxdt1g1x9x4f9j9ddsr8
[junit4] 2> 26770 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down scheduled thread pool executor now
[junit4] 2> 26770 INFO (Simulated OverseerAutoScalingTriggerThread) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 26770 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 26771 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 26771 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 26772 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 26772 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 26776 DEBUG (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.c.a.s.SnapshotDistribStateManager - loaded snapshot of 23 resources
[junit4] 2> 26778 INFO (TEST-TestSimScenario.testAutoAddReplicas-seed#[490479DAEFF69BC3]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testAutoAddReplicas
[junit4] OK 0.93s J4 | TestSimScenario.testAutoAddReplicas
[junit4] 2> NOTE: leaving temporary files on disk at: C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\solr\build\solr-core\test\J4\temp\solr.cloud.autoscaling.sim.TestSimScenario_490479DAEFF69BC3-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene86): {}, docValues:{}, maxPointsInLeafNode=697, maxMBSortInHeap=7.216961918634162, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=es-AR, timezone=Australia/Adelaide
[junit4] 2> NOTE: Windows 10 10.0 amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=6,threads=1,free=96108928,total=115867648
[junit4] 2> NOTE: All tests run in this JVM: [TestSimScenario]
[junit4] Completed [3/5 (3!)] on J4 in 24.22s, 4 tests, 1 error <<< FAILURES!
[...truncated 29 lines...]
BUILD FAILED
C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\lucene\common-build.xml:1599: The following error occurred while executing this line:
C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows\lucene\common-build.xml:1126: There were test failures: 5 suites, 20 tests, 3 errors [seed: 490479DAEFF69BC3]
Total time: 30 seconds
[repro] Setting last failure code to 1
[repro] Failures w/original seeds:
[repro] 3/5 failed: org.apache.solr.cloud.autoscaling.sim.TestSimScenario
[repro] Exiting with code 1
C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows>IF EXIST lucene\build move /y lucene\build lucene\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows>IF EXIST solr\build move /y solr\build solr\build.repro
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows>IF EXIST lucene\build.orig move /y lucene\build.orig lucene\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows>IF EXIST solr\build.orig move /y solr\build.orig solr\build
1 dir(s) moved.
C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows>exit 0
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Archiving artifacts
[Java] Sleeping for 5 seconds due to JENKINS-32191...
[Java] Parsing console log (workspace: 'C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows')
[Java] Successfully parsed console log
[Java] -> found 113 issues (skipped 104 duplicates)
[Java] Post processing issues on 'Windows VBOX' with source code encoding 'UTF-8'
[Java] Creating SCM blamer to obtain author and commit information for affected files
[Java] Creating SCM miner to obtain statistics for affected repository files
[Java] Resolving file names for all issues in source directory 'C:\Users\jenkins\workspace\Lucene-Solr-8.6-Windows'
[Java] -> resolved paths in source directory (54 found, 0 not found)
[Java] Resolving module names from module definitions (build.xml, pom.xml, or Manifest.mf files)
[Java] -> resolved module names for 113 issues
[Java] Resolving package names (or namespaces) by parsing the affected files
[Java] -> resolved package names of 54 affected files
[Java] No filter has been set, publishing all 113 issues
[Java] Creating fingerprints for all affected code blocks to track issues over different builds
[Java] -> created fingerprints for 113 issues (skipped 0 issues)
[Java] Copying affected files to Jenkins' build folder '/home/jenkins/jobs/Lucene-Solr-8.6-Windows/builds/354/files-with-issues'
[Java] -> 54 copied, 0 not in workspace, 0 not-found, 0 with I/O error
[Java] Using reference build 'Lucene-Solr-8.6-Windows #353' to compute new, fixed, and outstanding issues
[Java] Issues delta (vs. reference build): outstanding: 113, new: 0, fixed: 0
[Java] No quality gates have been set - skipping
[Java] Health report is disabled - skipping
[Java] Created analysis result for 113 issues (found 0 new issues, fixed 0 issues)
[Java] Attaching ResultAction with ID 'java' to run 'Lucene-Solr-8.6-Windows #354'.
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Recording test results
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Build step 'Publish JUnit test result report' changed build result to UNSTABLE
Email was triggered for: Unstable (Test Failures)
Sending email for trigger: Unstable (Test Failures)
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2
Setting ANT_1_8_2_HOME=C:\Users\jenkins\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2