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/09/10 00:00:09 UTC
[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk1.8.0_201) - Build # 4343
- Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/4343/
Java: 64bit/jdk1.8.0_201 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting
Error Message:
Should have exactly 4 documents returned expected:<4> but was:<3>
Stack Trace:
java.lang.AssertionError: Should have exactly 4 documents returned expected:<4> but was:<3>
at __randomizedtesting.SeedInfo.seed([A68818E4FE83DAA:145089863343872A]:0)
at org.junit.Assert.fail(Assert.java:88)
at org.junit.Assert.failNotEquals(Assert.java:834)
at org.junit.Assert.assertEquals(Assert.java:645)
at org.apache.solr.cloud.DocValuesNotIndexedTest.checkSortOrder(DocValuesNotIndexedTest.java:273)
at org.apache.solr.cloud.DocValuesNotIndexedTest.testGroupingSorting(DocValuesNotIndexedTest.java:258)
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 14923 lines...]
[junit4] Suite: org.apache.solr.cloud.DocValuesNotIndexedTest
[junit4] 2> 623456 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 623456 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/data-dir-88-001
[junit4] 2> 623456 WARN (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=10 numCloses=10
[junit4] 2> 623456 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 623457 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 623457 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 623458 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001
[junit4] 2> 623458 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 623459 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 623459 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 623460 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 623559 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 37503
[junit4] 2> 623560 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:37503
[junit4] 2> 623560 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37503
[junit4] 2> 623560 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37503
[junit4] 2> 623562 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623564 INFO (zkConnectionManagerCallback-8936-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623564 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 623567 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623568 INFO (zkConnectionManagerCallback-8938-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623568 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 623568 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623569 INFO (zkConnectionManagerCallback-8940-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623569 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 623672 WARN (jetty-launcher-8941-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 623672 WARN (jetty-launcher-8941-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 623672 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 623672 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 623672 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 623672 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 623672 INFO (jetty-launcher-8941-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 623672 INFO (jetty-launcher-8941-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 1.8.0_201-b09
[junit4] 2> 623674 INFO (jetty-launcher-8941-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 623674 INFO (jetty-launcher-8941-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 623674 INFO (jetty-launcher-8941-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 623674 INFO (jetty-launcher-8941-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 623674 INFO (jetty-launcher-8941-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 623674 INFO (jetty-launcher-8941-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 623675 INFO (jetty-launcher-8941-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3cb0aa8c{/solr,null,AVAILABLE}
[junit4] 2> 623675 INFO (jetty-launcher-8941-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@f909a2{/solr,null,AVAILABLE}
[junit4] 2> 623675 INFO (jetty-launcher-8941-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@282cb641{SSL, (ssl, http/1.1)}{127.0.0.1:46055}
[junit4] 2> 623675 INFO (jetty-launcher-8941-thread-1) [ ] o.e.j.s.Server Started @623704ms
[junit4] 2> 623675 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=46055}
[junit4] 2> 623675 INFO (jetty-launcher-8941-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@72a4d6e4{SSL, (ssl, http/1.1)}{127.0.0.1:45911}
[junit4] 2> 623675 INFO (jetty-launcher-8941-thread-2) [ ] o.e.j.s.Server Started @623704ms
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45911}
[junit4] 2> 623676 ERROR (jetty-launcher-8941-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 623676 ERROR (jetty-launcher-8941-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-09-09T23:36:54.068Z
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-09-09T23:36:54.068Z
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623676 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623677 INFO (zkConnectionManagerCallback-8945-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623677 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 623677 INFO (zkConnectionManagerCallback-8944-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623677 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 623677 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 623677 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 623841 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 623841 WARN (jetty-launcher-8941-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 623844 WARN (jetty-launcher-8941-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4687c4d8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 623844 WARN (jetty-launcher-8941-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4687c4d8[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 623846 WARN (jetty-launcher-8941-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 623848 WARN (jetty-launcher-8941-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1d9c86da[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 623848 WARN (jetty-launcher-8941-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1d9c86da[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 623849 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37503/solr
[junit4] 2> 623849 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623850 INFO (zkConnectionManagerCallback-8955-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623850 INFO (jetty-launcher-8941-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 623916 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 623917 WARN (jetty-launcher-8941-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 623917 WARN (jetty-launcher-8941-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@155654a2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 623917 WARN (jetty-launcher-8941-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@155654a2[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 623919 WARN (jetty-launcher-8941-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 623919 WARN (jetty-launcher-8941-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2dd4ce8b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 623919 WARN (jetty-launcher-8941-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2dd4ce8b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 623920 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37503/solr
[junit4] 2> 623920 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623921 INFO (zkConnectionManagerCallback-8965-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623921 INFO (jetty-launcher-8941-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 623985 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 623986 INFO (zkConnectionManagerCallback-8967-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 623986 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 624020 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45911_solr
[junit4] 2> 624020 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.c.Overseer Overseer (id=72106038780297223-127.0.0.1:45911_solr-n_0000000000) starting
[junit4] 2> 624027 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45911_solr
[junit4] 2> 624027 INFO (OverseerStateUpdate-72106038780297223-127.0.0.1:45911_solr-n_0000000000) [n:127.0.0.1:45911_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:45911_solr
[junit4] 2> 624027 INFO (OverseerStateUpdate-72106038780297223-127.0.0.1:45911_solr-n_0000000000) [n:127.0.0.1:45911_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 624034 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 624034 WARN (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_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> 624042 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 624055 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45911.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624059 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45911.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624059 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45911.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624060 INFO (jetty-launcher-8941-thread-2) [n:127.0.0.1:45911_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node2
[junit4] 2> 624083 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 624084 INFO (zkConnectionManagerCallback-8981-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 624084 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 624086 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 624087 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:46055_solr as DOWN
[junit4] 2> 624088 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 624088 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46055_solr
[junit4] 2> 624088 INFO (zkCallback-8980-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 624088 INFO (zkCallback-8966-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 624089 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 624089 WARN (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_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> 624097 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 624108 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46055.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624112 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46055.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624112 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46055.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624113 INFO (jetty-launcher-8941-thread-1) [n:127.0.0.1:46055_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node1
[junit4] 2> 624122 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 624122 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 624123 INFO (zkConnectionManagerCallback-8994-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 624123 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 624123 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 624124 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37503/solr ready
[junit4] 2> 624133 INFO (qtp2019343764-15605) [n:127.0.0.1:45911_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 624135 INFO (OverseerThreadFactory-8974-thread-1-processing-n:127.0.0.1:45911_solr) [n:127.0.0.1:45911_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection dv_coll
[junit4] 2> 624239 INFO (OverseerStateUpdate-72106038780297223-127.0.0.1:45911_solr-n_0000000000) [n:127.0.0.1:45911_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"dv_coll_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46055/solr",
[junit4] 2> "node_name":"127.0.0.1:46055_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 624240 INFO (OverseerStateUpdate-72106038780297223-127.0.0.1:45911_solr-n_0000000000) [n:127.0.0.1:45911_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"dv_coll_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45911/solr",
[junit4] 2> "node_name":"127.0.0.1:45911_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 624241 INFO (OverseerStateUpdate-72106038780297223-127.0.0.1:45911_solr-n_0000000000) [n:127.0.0.1:45911_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard3",
[junit4] 2> "core":"dv_coll_shard3_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:46055/solr",
[junit4] 2> "node_name":"127.0.0.1:46055_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 624242 INFO (OverseerStateUpdate-72106038780297223-127.0.0.1:45911_solr-n_0000000000) [n:127.0.0.1:45911_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"dv_coll",
[junit4] 2> "shard":"shard4",
[junit4] 2> "core":"dv_coll_shard4_replica_n6",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45911/solr",
[junit4] 2> "node_name":"127.0.0.1:45911_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 624463 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr x:dv_coll_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n2&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 624464 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr x:dv_coll_shard2_replica_n2 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 624464 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr x:dv_coll_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 624464 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr x:dv_coll_shard3_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n4&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 624465 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr x:dv_coll_shard4_replica_n6 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n6&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 624473 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 624473 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 624473 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 624473 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 624479 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard1_replica_n1' using configuration from configset conf1, trusted=true
[junit4] 2> 624480 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard2_replica_n2' using configuration from configset conf1, trusted=true
[junit4] 2> 624481 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard4_replica_n6' using configuration from configset conf1, trusted=true
[junit4] 2> 624481 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.CoreContainer Creating SolrCore 'dv_coll_shard3_replica_n4' using configuration from configset conf1, trusted=true
[junit4] 2> 624482 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624482 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624483 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624483 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624493 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624493 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46055.solr.core.dv_coll.shard1.replica_n1' (registry 'solr.core.dv_coll.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624493 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624493 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624493 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46055.solr.core.dv_coll.shard3.replica_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624494 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.SolrCore [[dv_coll_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node1/dv_coll_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node1/dv_coll_shard1_replica_n1/data/]
[junit4] 2> 624494 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SolrCore [[dv_coll_shard3_replica_n4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node1/dv_coll_shard3_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node1/dv_coll_shard3_replica_n4/data/]
[junit4] 2> 624494 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45911.solr.core.dv_coll.shard2.replica_n2' (registry 'solr.core.dv_coll.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624494 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624494 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.SolrCore [[dv_coll_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node2/dv_coll_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node2/dv_coll_shard2_replica_n2/data/]
[junit4] 2> 624494 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45911.solr.core.dv_coll.shard4.replica_n6' (registry 'solr.core.dv_coll.shard4.replica_n6') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624494 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.SolrCore [[dv_coll_shard4_replica_n6] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node2/dv_coll_shard4_replica_n6], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node2/dv_coll_shard4_replica_n6/data/]
[junit4] 2> 624529 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 624529 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 624530 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 624530 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 624530 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 624530 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 624531 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 624531 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 624531 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 624531 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 624531 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 624531 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 624531 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 624531 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 624531 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 624531 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 624531 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 624531 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 624532 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 624532 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 624532 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 624532 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 624532 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677401380538548224
[junit4] 2> 624533 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 624533 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 624533 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 624533 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 624533 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 624533 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 624534 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 624534 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 624534 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 624534 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 624534 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 624534 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 624534 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677401380540645376
[junit4] 2> 624534 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677401380540645376
[junit4] 2> 624534 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 624534 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 624534 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677401380540645376
[junit4] 2> 624535 INFO (searcherExecutor-8996-thread-1-processing-n:127.0.0.1:46055_solr x:dv_coll_shard1_replica_n1 c:dv_coll s:shard1 r:core_node3) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 624536 INFO (searcherExecutor-9000-thread-1-processing-n:127.0.0.1:45911_solr x:dv_coll_shard4_replica_n6 c:dv_coll s:shard4 r:core_node8) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] Registered new searcher autowarm time: 0 ms
[junit4] 2> 624536 INFO (searcherExecutor-9002-thread-1-processing-n:127.0.0.1:46055_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 624536 INFO (searcherExecutor-8998-thread-1-processing-n:127.0.0.1:45911_solr x:dv_coll_shard2_replica_n2 c:dv_coll s:shard2 r:core_node5) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 624537 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/dv_coll/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 624537 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/dv_coll/leaders/shard1
[junit4] 2> 624538 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/dv_coll/terms/shard4 to Terms{values={core_node8=0}, version=0}
[junit4] 2> 624538 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/dv_coll/leaders/shard4
[junit4] 2> 624538 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/dv_coll/terms/shard3 to Terms{values={core_node7=0}, version=0}
[junit4] 2> 624538 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/dv_coll/leaders/shard3
[junit4] 2> 624539 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 624539 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 624539 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:46055/solr/dv_coll_shard1_replica_n1/
[junit4] 2> 624539 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 624540 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/dv_coll/terms/shard2 to Terms{values={core_node5=0}, version=0}
[junit4] 2> 624540 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:46055/solr/dv_coll_shard1_replica_n1/ has no replicas
[junit4] 2> 624540 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/dv_coll/leaders/shard1/leader after winning as /collections/dv_coll/leader_elect/shard1/election/72106038780297224-core_node3-n_0000000000
[junit4] 2> 624540 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/dv_coll/leaders/shard2
[junit4] 2> 624540 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 624540 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 624540 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:45911/solr/dv_coll_shard4_replica_n6/
[junit4] 2> 624541 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 624541 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 624541 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 624541 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:46055/solr/dv_coll_shard3_replica_n4/
[junit4] 2> 624541 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.SyncStrategy https://127.0.0.1:45911/solr/dv_coll_shard4_replica_n6/ has no replicas
[junit4] 2> 624541 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/dv_coll/leaders/shard4/leader after winning as /collections/dv_coll/leader_elect/shard4/election/72106038780297223-core_node8-n_0000000000
[junit4] 2> 624541 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 624541 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:46055/solr/dv_coll_shard1_replica_n1/ shard1
[junit4] 2> 624541 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SyncStrategy https://127.0.0.1:46055/solr/dv_coll_shard3_replica_n4/ has no replicas
[junit4] 2> 624541 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/dv_coll/leaders/shard3/leader after winning as /collections/dv_coll/leader_elect/shard3/election/72106038780297224-core_node7-n_0000000000
[junit4] 2> 624542 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:46055/solr/dv_coll_shard3_replica_n4/ shard3
[junit4] 2> 624542 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 624542 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 624542 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:45911/solr/dv_coll_shard2_replica_n2/
[junit4] 2> 624542 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:45911/solr/dv_coll_shard4_replica_n6/ shard4
[junit4] 2> 624542 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 624542 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:45911/solr/dv_coll_shard2_replica_n2/ has no replicas
[junit4] 2> 624542 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/dv_coll/leaders/shard2/leader after winning as /collections/dv_coll/leader_elect/shard2/election/72106038780297223-core_node5-n_0000000000
[junit4] 2> 624543 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:45911/solr/dv_coll_shard2_replica_n2/ shard2
[junit4] 2> 624644 INFO (zkCallback-8966-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624644 INFO (zkCallback-8980-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624645 INFO (zkCallback-8966-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624645 INFO (zkCallback-8980-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624645 INFO (zkCallback-8966-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624645 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 624645 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 624645 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 624645 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 624648 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node7&collection.configName=conf1&newCollection=true&name=dv_coll_shard3_replica_n4&action=CREATE&numShards=4&collection=dv_coll&shard=shard3&wt=javabin&version=2&replicaType=NRT} status=0 QTime=184
[junit4] 2> 624648 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf1&newCollection=true&name=dv_coll_shard1_replica_n1&action=CREATE&numShards=4&collection=dv_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=184
[junit4] 2> 624648 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node8&collection.configName=conf1&newCollection=true&name=dv_coll_shard4_replica_n6&action=CREATE&numShards=4&collection=dv_coll&shard=shard4&wt=javabin&version=2&replicaType=NRT} status=0 QTime=183
[junit4] 2> 624648 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node5&collection.configName=conf1&newCollection=true&name=dv_coll_shard2_replica_n2&action=CREATE&numShards=4&collection=dv_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=184
[junit4] 2> 624650 INFO (qtp2019343764-15605) [n:127.0.0.1:45911_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> 624747 INFO (zkCallback-8980-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624747 INFO (zkCallback-8980-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624747 INFO (zkCallback-8966-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624747 INFO (zkCallback-8966-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624747 INFO (zkCallback-8980-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624747 INFO (zkCallback-8966-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624747 INFO (zkCallback-8966-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/dv_coll/state.json] for collection [dv_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 624748 INFO (qtp2019343764-15605) [n:127.0.0.1:45911_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf1&maxShardsPerNode=2&name=dv_coll&nrtReplicas=1&action=CREATE&numShards=4&wt=javabin&version=2} status=0 QTime=615
[junit4] 2> 624748 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: dv_coll
[junit4] 2> 624761 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.SchemaManager managed schema loaded . version : 0
[junit4] 2> 624763 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624765 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624772 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.ZkController Persisted config data to node /configs/conf1/managed-schema
[junit4] 2> 624772 INFO (zkCallback-8966-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 624772 INFO (zkCallback-8980-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 624772 INFO (zkCallback-8980-thread-1) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 624772 INFO (zkCallback-8966-thread-4) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/conf1/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 624773 INFO (zkCallback-8966-thread-4) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from Zookeeper
[junit4] 2> 624773 INFO (zkCallback-8966-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from Zookeeper
[junit4] 2> 624773 INFO (zkCallback-8980-thread-1) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from Zookeeper
[junit4] 2> 624773 INFO (Thread-3809) [ ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard3_replica_n4
[junit4] 2> 624774 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.c.SolrCore core reload dv_coll_shard3_replica_n4
[junit4] 2> 624774 INFO (zkCallback-8980-thread-1) [ ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624774 INFO (zkCallback-8966-thread-3) [ ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624774 INFO (zkCallback-8966-thread-4) [ ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624774 INFO (Thread-3810) [ ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard2_replica_n2
[junit4] 2> 624776 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.c.SolrCore core reload dv_coll_shard2_replica_n2
[junit4] 2> 624777 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 624797 INFO (zkCallback-8966-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624798 INFO (zkCallback-8966-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 24 ms
[junit4] 2> 624798 INFO (zkCallback-8966-thread-4) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624798 INFO (zkCallback-8966-thread-4) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 24 ms
[junit4] 2> 624798 INFO (zkCallback-8980-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624798 INFO (zkCallback-8980-thread-1) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 25 ms
[junit4] 2> 624802 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard3_replica_n4' using configuration from configset conf1
[junit4] 2> 624803 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 624805 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624813 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard2_replica_n2' using configuration from configset conf1
[junit4] 2> 624816 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624817 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624824 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624848 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46055.solr.core.dv_coll.shard3.replica_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624848 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SolrCore [[dv_coll_shard3_replica_n4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node1/dv_coll_shard3_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node1/dv_coll_shard3_replica_n4/data/]
[junit4] 2> 624850 INFO (Thread-3810) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45911.solr.core.dv_coll.shard2.replica_n2' (registry 'solr.core.dv_coll.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624850 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.c.SolrCore [[dv_coll_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node2/dv_coll_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node2/dv_coll_shard2_replica_n2/data/]
[junit4] 2> 624883 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 624883 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 624884 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 624885 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 624885 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 624885 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 624885 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 624885 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677401380908695552
[junit4] 2> 624886 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 624886 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 624887 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 624887 INFO (searcherExecutor-9022-thread-1-processing-n:127.0.0.1:45911_solr) [n:127.0.0.1:45911_solr ] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 624887 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@43b0d73e
[junit4] 2> 624887 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.dv_coll.shard2.replica_n2 tag=SolrCore@43b0d73e
[junit4] 2> 624887 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@7c83ce66: rootName = solr_45911, domain = solr.core.dv_coll.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.dv_coll.shard2.replica_n2/com.codahale.metrics.MetricRegistry@379b0e41
[junit4] 2> 624888 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 624888 INFO (searcherExecutor-9022-thread-1-processing-n:127.0.0.1:45911_solr) [n:127.0.0.1:45911_solr ] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 624888 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 624888 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 624888 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 624888 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 624888 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677401380911841280
[junit4] 2> 624890 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 624891 INFO (searcherExecutor-9020-thread-1-processing-n:127.0.0.1:46055_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 624891 INFO (zkCallback-8980-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from Zookeeper
[junit4] 2> 624892 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr ] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 3 replicas to apply schema update version 1 for collection dv_coll
[junit4] 2> 624892 INFO (searcherExecutor-9020-thread-1-processing-n:127.0.0.1:46055_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 624893 INFO (zkCallback-8980-thread-2) [ ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624893 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard2.leader tag=SolrCore@43b0d73e
[junit4] 2> 624894 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 624894 INFO (Thread-3810) [n:127.0.0.1:45911_solr ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard4_replica_n6
[junit4] 2> 624895 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.S.Request [dv_coll_shard2_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 624895 INFO (qtp2019343764-15605) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.S.Request [dv_coll_shard4_replica_n6] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 624895 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.S.Request [dv_coll_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 624896 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr ] o.a.s.s.ManagedIndexSchema Took 5.0ms for 3 replicas to apply schema update version 1 for collection dv_coll
[junit4] 2> 624896 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr ] o.a.s.c.S.Request [dv_coll_shard3_replica_n4] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=136
[junit4] 2> 624896 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr ] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] CLOSING SolrCore org.apache.solr.core.SolrCore@8cb3ee
[junit4] 2> 624896 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.dv_coll.shard3.replica_n4 tag=SolrCore@8cb3ee
[junit4] 2> 624897 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@60a34ca1: rootName = solr_46055, domain = solr.core.dv_coll.shard3.replica_n4, service url = null, agent id = null] for registry solr.core.dv_coll.shard3.replica_n4/com.codahale.metrics.MetricRegistry@63dbd23a
[junit4] 2> 624899 INFO (zkCallback-8980-thread-2) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624899 INFO (zkCallback-8980-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 7 ms
[junit4] 2> 624899 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'dv_coll_shard3_replica_n4' using configuration from configset conf1
[junit4] 2> 624902 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 624905 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.dv_coll.shard3.leader tag=SolrCore@8cb3ee
[junit4] 2> 624905 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 624967 INFO (TEST-DocValuesNotIndexedTest.testGroupingSorting-seed#[A68818E4FE83DAA]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testGroupingSorting
[junit4] 2> 624976 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/dv_coll/terms/shard2 to Terms{values={core_node5=1}, version=1}
[junit4] 2> 624976 INFO (Thread-3809) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_46055.solr.core.dv_coll.shard3.replica_n4' (registry 'solr.core.dv_coll.shard3.replica_n4') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@777f5076
[junit4] 2> 624977 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.c.SolrCore [[dv_coll_shard3_replica_n4] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node1/dv_coll_shard3_replica_n4], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001/tempDir-001/node1/dv_coll_shard3_replica_n4/data/]
[junit4] 2> 624997 INFO (qtp2019343764-15605) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/dv_coll/terms/shard4 to Terms{values={core_node8=1}, version=1}
[junit4] 2> 624997 INFO (qtp2019343764-15605) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:45911/solr/dv_coll_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1677401381020893184)} 0 6
[junit4] 2> 624997 INFO (qtp584375601-15658) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/dv_coll/terms/shard1 to Terms{values={core_node3=1}, version=1}
[junit4] 2> 624998 INFO (qtp584375601-15658) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:45911/solr/dv_coll_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1677401381022990336)} 0 7
[junit4] 2> 624998 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/dv_coll/terms/shard3 to Terms{values={core_node7=1}, version=1}
[junit4] 2> 624998 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr path=/update params={update.distrib=TOLEADER&distrib.from=https://127.0.0.1:45911/solr/dv_coll_shard2_replica_n2/&wt=javabin&version=2}{deleteByQuery=*:* (-1677401381021941760)} 0 8
[junit4] 2> 625000 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1677401381000970240)} 0 32
[junit4] 2> 625019 INFO (qtp584375601-15614) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46055/solr/dv_coll_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 625019 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46055/solr/dv_coll_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 625019 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 625019 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 625019 INFO (searcherExecutor-9000-thread-1-processing-n:127.0.0.1:45911_solr x:dv_coll_shard4_replica_n6 c:dv_coll s:shard4 r:core_node8) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.c.SolrCore [dv_coll_shard4_replica_n6] Registered new searcher autowarm time: 0 ms
[junit4] 2> 625019 INFO (qtp2019343764-15605) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46055/solr/dv_coll_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 1
[junit4] 2> 625020 INFO (searcherExecutor-8996-thread-1-processing-n:127.0.0.1:46055_solr x:dv_coll_shard1_replica_n1 c:dv_coll s:shard1 r:core_node3) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.c.SolrCore [dv_coll_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 625020 INFO (qtp584375601-15657) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=dv_coll:3&waitSearcher=true&commit=true&softCommit=false&wt=javabin&version=2}{commit=} 0 19
[junit4] 2> 625020 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 625021 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 625021 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/conf1/managed-schema
[junit4] 2> 625021 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 625021 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 625024 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 625025 INFO (qtp2019343764-15615) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr path=/update params={_stateVer_=dv_coll:3&wt=javabin&version=2}{add=[4 (1677401381054447616)]} 0 1
[junit4] 2> 625025 INFO (searcherExecutor-9034-thread-1-processing-n:127.0.0.1:46055_solr) [n:127.0.0.1:46055_solr ] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 625026 INFO (qtp584375601-15658) [n:127.0.0.1:46055_solr c:dv_coll s:shard1 r:core_node3 x:dv_coll_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=dv_coll:3&wt=javabin&version=2}{add=[1 (1677401381054447616)]} 0 2
[junit4] 2> 625026 INFO (qtp584375601-15617) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard3_replica_n4] webapp=/solr path=/update params={_stateVer_=dv_coll:3&wt=javabin&version=2}{add=[2 (1677401381055496192), 3 (1677401381056544768)]} 0 1
[junit4] 2> 625029 INFO (qtp2019343764-15616) [n:127.0.0.1:45911_solr c:dv_coll s:shard4 r:core_node8 x:dv_coll_shard4_replica_n6 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard4_replica_n6] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46055/solr/dv_coll_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 0
[junit4] 2> 625034 INFO (Thread-3809) [n:127.0.0.1:46055_solr ] o.a.s.c.SolrCore config update listener called for core dv_coll_shard1_replica_n1
[junit4] 2> 625035 INFO (searcherExecutor-9022-thread-1-processing-n:127.0.0.1:45911_solr x:dv_coll_shard2_replica_n2 c:dv_coll s:shard2 r:core_node5) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.c.SolrCore [dv_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 625035 INFO (qtp2019343764-15605) [n:127.0.0.1:45911_solr c:dv_coll s:shard2 r:core_node5 x:dv_coll_shard2_replica_n2 ] o.a.s.u.p.LogUpdateProcessorFactory [dv_coll_shard2_replica_n2] webapp=/solr path=/update params={update.distrib=TOLEADER&waitSearcher=true&openSearcher=true&commit=true&softCommit=false&distrib.from=https://127.0.0.1:46055/solr/dv_coll_shard1_replica_n1/&commit_end_point=leaders&wt=javabin&version=2&expungeDeletes=false}{commit=} 0 5
[junit4] 2> 625036 INFO (searcherExecutor-9034-thread-1-processing-n:127.0.0.1:46055_solr) [n:127.0.0.1:46055_solr ] o.a.s.c.SolrCore [dv_coll_shard3_replica_n4] Registered new searcher autowarm time: 0 ms
[junit4] 2> 625041 INFO (searcherExecutor-9020-thread-1-processing-n:127.0.0.1:46055_solr x:dv_coll_shard3_replica_n4 c:dv_coll s:shard3 r:core_node7) [n:127.0.0.1:46055_solr c:dv_coll s:shard3 r:core_node7 x:dv_coll_shard3_replica_n4 ] o.a.s.c.SolrCore [dv_co
[...truncated too long message...]
=72106038780297223-127.0.0.1:45911_solr-n_0000000000) closing
[junit4] 2> 625796 INFO (OverseerStateUpdate-72106038780297223-127.0.0.1:45911_solr-n_0000000000) [n:127.0.0.1:45911_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:45911_solr
[junit4] 2> 625798 INFO (OverseerAutoScalingTriggerThread-72106038780297223-127.0.0.1:45911_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 625800 WARN (jetty-launcher-8941-thread-1-SendThread(127.0.0.1:37503)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1002c0f6ba90008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1002c0f6ba90008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1002c0f6ba90008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 625806 INFO (closeThreadPool-9050-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72106038780297223-127.0.0.1:45911_solr-n_0000000000) closing
[junit4] 2> 625901 INFO (jetty-closer-9038-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@282cb641{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 625901 INFO (jetty-closer-9038-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@f909a2{/solr,null,UNAVAILABLE}
[junit4] 2> 625901 INFO (jetty-closer-9038-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 626000 INFO (jetty-closer-9038-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72106038780297223-127.0.0.1:45911_solr-n_0000000000) closing
[junit4] 2> 626001 INFO (jetty-closer-9038-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@72a4d6e4{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 626001 INFO (jetty-closer-9038-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@3cb0aa8c{/solr,null,UNAVAILABLE}
[junit4] 2> 626001 INFO (jetty-closer-9038-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 626002 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 626204 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/configs/conf1/managed-schema
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/collections/dv_coll/terms/shard3
[junit4] 2> 3 /solr/collections/dv_coll/terms/shard2
[junit4] 2> 3 /solr/collections/dv_coll/terms/shard1
[junit4] 2> 3 /solr/collections/dv_coll/terms/shard4
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/dv_coll/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 23 /solr/collections/dv_coll/state.json
[junit4] 2> 4 /solr/configs/conf1/managed-schema
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 20 /solr/overseer/queue
[junit4] 2> 6 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 626206 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:37503
[junit4] 2> 626206 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:37503
[junit4] 2> 626206 INFO (SUITE-DocValuesNotIndexedTest-seed#[A68818E4FE83DAA]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 37503
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.cloud.DocValuesNotIndexedTest_A68818E4FE83DAA-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {_root_=Lucene84, id=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{floatField=DocValuesFormat(name=Lucene80), doubleField=DocValuesFormat(name=Direct), intFieldMulti=DocValuesFormat(name=Lucene80), sortableGSL=DocValuesFormat(name=Lucene80), boolFieldMulti=DocValuesFormat(name=Direct), floatGSL=DocValuesFormat(name=Lucene80), longFieldMulti=DocValuesFormat(name=Asserting), sortableGSF=DocValuesFormat(name=Lucene80), intField=DocValuesFormat(name=Direct), boolField=DocValuesFormat(name=Lucene80), sortableFieldMulti=DocValuesFormat(name=Asserting), stringFieldMulti=DocValuesFormat(name=Lucene80), dateFieldMulti=DocValuesFormat(name=Asserting), longGSF=DocValuesFormat(name=Lucene80), doubleFieldMulti=DocValuesFormat(name=Lucene80), stringGSL=DocValuesFormat(name=Asserting), doubleGSF=DocValuesFormat(name=Direct), doubleGSL=DocValuesFormat(name=Asserting), stringGSF=DocValuesFormat(name=Direct), intGSL=DocValuesFormat(name=Direct), sortableText=DocValuesFormat(name=Asserting), longField=DocValuesFormat(name=Lucene80), dateField=DocValuesFormat(name=Lucene80), dateGSF=DocValuesFormat(name=Lucene80), boolGSF=DocValuesFormat(name=Lucene80), longGSL=DocValuesFormat(name=Lucene80), boolGSL=DocValuesFormat(name=Lucene80), floatFieldMulti=DocValuesFormat(name=Direct), floatGSF=DocValuesFormat(name=Lucene80), dateGSL=DocValuesFormat(name=Lucene80), intGSF=DocValuesFormat(name=Asserting), stringField=DocValuesFormat(name=Direct)}, maxPointsInLeafNode=1482, maxMBSortInHeap=5.668564263354864, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=sv-SE, timezone=America/Dominica
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_201 (64-bit)/cpus=16,threads=1,free=197761176,total=520093696
[junit4] 2> NOTE: All tests run in this JVM: [SampleTest, TestSystemIdResolver, TestCloudDeleteByQuery, DateMathParserTest, SparseHLLTest, TestNamedUpdateProcessors, SolrLogAuditLoggerPluginTest, TriggerEventQueueTest, TestLRUCache, TestScoreJoinQPNoScore, ConfigSetsAPITest, ZkDynamicConfigTest, TestNoOpRegenerator, UniqFieldsUpdateProcessorFactoryTest, TestNumericTokenStream, IndexBasedSpellCheckerTest, SmileWriterTest, TestCloudRecovery2, TestDistributedMissingSort, DistribJoinFromCollectionTest, ScriptEngineTest, TestSubQueryTransformerDistrib, TestTolerantUpdateProcessorRandomCloud, TestStressCloudBlindAtomicUpdates, TestAtomicUpdateErrorCases, TestRecovery, TestSweetSpotSimilarityFactory, TestCollapseQParserPlugin, TestSimpleQParserPlugin, TestSortableTextField, MergeStrategyTest, PluginInfoTest, HighlighterConfigTest, FacetPivotSmallTest, TestExclusionRuleCollectionAccess, BitVectorTest, TestFieldCacheVsDocValues, RecoveryZkTest, TestDynamicFieldCollectionResource, TestManagedStopFilterFactory, ConfigureRecoveryStrategyTest, UpdateParamsTest, TestBM25SimilarityFactory, CursorPagingTest, TestTrie, AnalyticsQueryTest, FullSolrCloudDistribCmdsTest, TestCloudSchemaless, TestRandomDVFaceting, OverriddenZkACLAndCredentialsProvidersTest, HdfsChaosMonkeySafeLeaderTest, SuggestComponentTest, TestRandomFlRTGCloud, TestInPlaceUpdatesStandalone, SpatialHeatmapFacetsTest, TestStressRecovery, DistributedFacetPivotLargeTest, BaseCdcrDistributedZkTest, TestInfoStreamLogging, TestSQLHandlerNonCloud, CdcrVersionReplicationTest, LeaderElectionTest, TestQuerySenderListener, SolrCoreCheckLockOnStartupTest, SignificantTermsQParserPluginTest, IndexSizeEstimatorTest, SolrMetricReporterTest, NodeLostTriggerIntegrationTest, TestSchemaNameResource, JavaBinAtomicUpdateMultivalueTest, TestCoreAdminApis, MoveReplicaTest, MetricsHistoryHandlerTest, TestJsonFacets, WordBreakSolrSpellCheckerTest, CreateRoutedAliasTest, DocValuesNotIndexedTest]
[junit4] Completed [471/919 (1!)] on J3 in 3.01s, 5 tests, 1 failure <<< FAILURES!
[...truncated 53926 lines...]
[repro] Jenkins log URL: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/4343/consoleText
[repro] Revision: 35b48572a755bef1e5cdaaea99161cf852620656
[repro] Ant options: "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC"
[repro] Repro line: ant test -Dtestcase=DocValuesNotIndexedTest -Dtests.method=testGroupingSorting -Dtests.seed=A68818E4FE83DAA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sv-SE -Dtests.timezone=America/Dominica -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[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/core
[repro] DocValuesNotIndexedTest
[repro] ant compile-test
[...truncated 3448 lines...]
[repro] ant test-nocompile -Dtests.dups=5 -Dtests.maxfailures=5 -Dtests.class="*.DocValuesNotIndexedTest" -Dtests.showOutput=onerror "-Dargs=-XX:+UseCompressedOops -XX:+UseParallelGC" -Dtests.seed=A68818E4FE83DAA -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=sv-SE -Dtests.timezone=America/Dominica -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[...truncated 96 lines...]
[repro] Failures w/original seeds:
[repro] 0/5 failed: org.apache.solr.cloud.DocValuesNotIndexedTest
[repro] Exiting with code 0
[...truncated 61 lines...]
[JENKINS] Lucene-Solr-8.x-Linux (64bit/jdk-14.0.1) - Build # 4344 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-8.x-Linux/4344/
Java: 64bit/jdk-14.0.1 -XX:+UseCompressedOops -XX:+UseParallelGC
3 tests failed.
FAILED: org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest.testAddFieldsRoundRobin
Error Message:
Error from server at https://127.0.0.1:34841/solr: No such core: managed_coll_shard2_replica_n2
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:34841/solr: No such core: managed_coll_shard2_replica_n2
at __randomizedtesting.SeedInfo.seed([774AEF7A9828F83A:2C5540583686B962]: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.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest.addField(ManagedSchemaRoundRobinCloudTest.java:82)
at org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest.testAddFieldsRoundRobin(ManagedSchemaRoundRobinCloudTest.java:69)
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)
FAILED: org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest.testAddFieldsRoundRobin
Error Message:
Error from server at https://127.0.0.1:42185/solr: No such core: managed_coll_shard1_replica_n1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:42185/solr: No such core: managed_coll_shard1_replica_n1
at __randomizedtesting.SeedInfo.seed([774AEF7A9828F83A:2C5540583686B962]: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.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest.addField(ManagedSchemaRoundRobinCloudTest.java:82)
at org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest.testAddFieldsRoundRobin(ManagedSchemaRoundRobinCloudTest.java:69)
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)
FAILED: org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest.testAddFieldsRoundRobin
Error Message:
Error from server at https://127.0.0.1:40697/solr: No such core: managed_coll_shard1_replica_n1
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:40697/solr: No such core: managed_coll_shard1_replica_n1
at __randomizedtesting.SeedInfo.seed([774AEF7A9828F83A:2C5540583686B962]: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.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest.addField(ManagedSchemaRoundRobinCloudTest.java:82)
at org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest.testAddFieldsRoundRobin(ManagedSchemaRoundRobinCloudTest.java:69)
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 13429 lines...]
[junit4] Suite: org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest
[junit4] 2> 19806 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/server/solr/configsets/_default/conf'
[junit4] 2> 19806 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 19806 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/data-dir-3-001
[junit4] 2> 19807 WARN (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=14 numCloses=14
[junit4] 2> 19807 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 19808 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", value=0.0/0.0, ssl=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 19811 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001
[junit4] 2> 19812 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 19812 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 19812 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 19813 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 19912 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 38501
[junit4] 2> 19912 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38501
[junit4] 2> 19912 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38501
[junit4] 2> 19912 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38501
[junit4] 2> 19919 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 19930 INFO (zkConnectionManagerCallback-227-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19930 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 19942 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 19947 INFO (zkConnectionManagerCallback-229-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19947 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 19955 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 19978 INFO (zkConnectionManagerCallback-231-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 19978 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 20098 WARN (jetty-launcher-232-thread-1) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 20098 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 20098 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 20098 INFO (jetty-launcher-232-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> 20099 WARN (jetty-launcher-232-thread-2) [ ] o.e.j.s.h.g.GzipHandler minGzipSize of 0 is inefficient for short content, break even is size 23
[junit4] 2> 20099 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 20099 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 20099 INFO (jetty-launcher-232-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> 20101 INFO (jetty-launcher-232-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 20101 INFO (jetty-launcher-232-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 20102 INFO (jetty-launcher-232-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 20102 INFO (jetty-launcher-232-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 20102 INFO (jetty-launcher-232-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 20102 INFO (jetty-launcher-232-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 20102 INFO (jetty-launcher-232-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1ae4c159{/solr,null,AVAILABLE}
[junit4] 2> 20103 INFO (jetty-launcher-232-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@723dd803{/solr,null,AVAILABLE}
[junit4] 2> 20106 INFO (jetty-launcher-232-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@48df857{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:40697}
[junit4] 2> 20106 INFO (jetty-launcher-232-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@681e18e6{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:45661}
[junit4] 2> 20106 INFO (jetty-launcher-232-thread-1) [ ] o.e.j.s.Server Started @20130ms
[junit4] 2> 20106 INFO (jetty-launcher-232-thread-2) [ ] o.e.j.s.Server Started @20130ms
[junit4] 2> 20106 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=45661}
[junit4] 2> 20106 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40697}
[junit4] 2> 20107 ERROR (jetty-launcher-232-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 20107 ERROR (jetty-launcher-232-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.7.0
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-09-10T01:31:42.266314Z
[junit4] 2> 20107 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-09-10T01:31:42.266326Z
[junit4] 2> 20108 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 20108 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 20111 INFO (zkConnectionManagerCallback-234-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20111 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 20113 INFO (zkConnectionManagerCallback-236-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20113 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 20114 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 20114 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 20379 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 20382 WARN (jetty-launcher-232-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@30ebbbb5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 20383 WARN (jetty-launcher-232-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@30ebbbb5[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 20414 WARN (jetty-launcher-232-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@172af1ad[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 20414 WARN (jetty-launcher-232-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@172af1ad[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 20415 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38501/solr
[junit4] 2> 20427 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 20442 INFO (zkConnectionManagerCallback-249-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20444 INFO (jetty-launcher-232-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 20456 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 20481 WARN (jetty-launcher-232-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5cdfce[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 20481 WARN (jetty-launcher-232-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5cdfce[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 20507 WARN (jetty-launcher-232-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@148a1a1c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 20508 WARN (jetty-launcher-232-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@148a1a1c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 20509 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38501/solr
[junit4] 2> 20510 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 20512 INFO (zkConnectionManagerCallback-256-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20512 INFO (jetty-launcher-232-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 20571 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 20575 INFO (zkConnectionManagerCallback-258-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20575 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 20664 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 20675 INFO (zkConnectionManagerCallback-262-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20675 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 20708 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45661_solr
[junit4] 2> 20711 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.c.Overseer Overseer (id=72106490202423304-127.0.0.1:45661_solr-n_0000000000) starting
[junit4] 2> 20724 INFO (OverseerStateUpdate-72106490202423304-127.0.0.1:45661_solr-n_0000000000) [n:127.0.0.1:45661_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:45661_solr
[junit4] 2> 20725 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45661_solr
[junit4] 2> 20739 INFO (OverseerStateUpdate-72106490202423304-127.0.0.1:45661_solr-n_0000000000) [n:127.0.0.1:45661_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 20742 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 20743 WARN (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_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> 20749 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 20778 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 20783 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:40697_solr as DOWN
[junit4] 2> 20785 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 20785 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40697_solr
[junit4] 2> 20786 INFO (zkCallback-261-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 20787 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 20788 WARN (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_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> 20798 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45661.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 20801 INFO (zkCallback-257-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 20806 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45661.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 20806 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45661.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 20808 INFO (jetty-launcher-232-thread-2) [n:127.0.0.1:45661_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node2
[junit4] 2> 20837 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 20850 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40697.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 20858 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40697.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 20859 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40697.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 20860 INFO (jetty-launcher-232-thread-1) [n:127.0.0.1:40697_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node1
[junit4] 2> 20874 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 20876 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 20877 INFO (zkConnectionManagerCallback-285-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 20877 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 20879 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 20882 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38501/solr ready
[junit4] 2> 20917 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=cloud-managed&maxShardsPerNode=1&name=managed_coll&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 20929 INFO (OverseerThreadFactory-269-thread-1-processing-n:127.0.0.1:45661_solr) [n:127.0.0.1:45661_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection managed_coll
[junit4] 2> 21045 INFO (OverseerStateUpdate-72106490202423304-127.0.0.1:45661_solr-n_0000000000) [n:127.0.0.1:45661_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"managed_coll",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"managed_coll_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:40697/solr",
[junit4] 2> "node_name":"127.0.0.1:40697_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 21048 INFO (OverseerStateUpdate-72106490202423304-127.0.0.1:45661_solr-n_0000000000) [n:127.0.0.1:45661_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"managed_coll",
[junit4] 2> "shard":"shard2",
[junit4] 2> "core":"managed_coll_shard2_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"https://127.0.0.1:45661/solr",
[junit4] 2> "node_name":"127.0.0.1:45661_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 21319 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr x:managed_coll_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=cloud-managed&newCollection=true&name=managed_coll_shard2_replica_n2&action=CREATE&numShards=2&collection=managed_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 21320 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr x:managed_coll_shard2_replica_n2 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 21336 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr x:managed_coll_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=cloud-managed&newCollection=true&name=managed_coll_shard1_replica_n1&action=CREATE&numShards=2&collection=managed_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 21337 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 21345 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'managed_coll_shard2_replica_n2' using configuration from configset cloud-managed, trusted=true
[junit4] 2> 21357 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 21367 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'managed_coll_shard1_replica_n1' using configuration from configset cloud-managed, trusted=true
[junit4] 2> 21372 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 21380 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 21399 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 21399 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45661.solr.core.managed_coll.shard2.replica_n2' (registry 'solr.core.managed_coll.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 21400 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SolrCore [[managed_coll_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node2/managed_coll_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node2/managed_coll_shard2_replica_n2/data/]
[junit4] 2> 21401 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 21402 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40697.solr.core.managed_coll.shard1.replica_n1' (registry 'solr.core.managed_coll.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 21402 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SolrCore [[managed_coll_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node1/managed_coll_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node1/managed_coll_shard1_replica_n1/data/]
[junit4] 2> 21475 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 21475 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 21476 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 21476 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 21479 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed
[junit4] 2> 21488 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed
[junit4] 2> 21490 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed/managed-schema
[junit4] 2> 21492 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 21492 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 21492 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677408603892350976
[junit4] 2> 21494 INFO (searcherExecutor-287-thread-1-processing-n:127.0.0.1:45661_solr x:managed_coll_shard2_replica_n2 c:managed_coll s:shard2 r:core_node4) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 21498 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/managed_coll/terms/shard2 to Terms{values={core_node4=0}, version=0}
[junit4] 2> 21498 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/managed_coll/leaders/shard2
[junit4] 2> 21504 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 21504 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 21506 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 21506 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 21507 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 21507 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 21508 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:45661/solr/managed_coll_shard2_replica_n2/
[junit4] 2> 21508 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 21508 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:45661/solr/managed_coll_shard2_replica_n2/ has no replicas
[junit4] 2> 21508 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/managed_coll/leaders/shard2/leader after winning as /collections/managed_coll/leader_elect/shard2/election/72106490202423304-core_node4-n_0000000000
[junit4] 2> 21508 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed
[junit4] 2> 21509 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed
[junit4] 2> 21509 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed/managed-schema
[junit4] 2> 21509 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 21509 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 21509 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:45661/solr/managed_coll_shard2_replica_n2/ shard2
[junit4] 2> 21510 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677408603911225344
[junit4] 2> 21514 INFO (searcherExecutor-289-thread-1-processing-n:127.0.0.1:40697_solr x:managed_coll_shard1_replica_n1 c:managed_coll s:shard1 r:core_node3) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SolrCore [managed_coll_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 21517 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/managed_coll/terms/shard1 to Terms{values={core_node3=0}, version=0}
[junit4] 2> 21517 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/managed_coll/leaders/shard1
[junit4] 2> 21524 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 21524 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 21524 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40697/solr/managed_coll_shard1_replica_n1/
[junit4] 2> 21524 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 21525 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:40697/solr/managed_coll_shard1_replica_n1/ has no replicas
[junit4] 2> 21526 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/managed_coll/leaders/shard1/leader after winning as /collections/managed_coll/leader_elect/shard1/election/72106490202423303-core_node3-n_0000000000
[junit4] 2> 21535 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:40697/solr/managed_coll_shard1_replica_n1/ shard1
[junit4] 2> 21638 INFO (zkCallback-261-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21638 INFO (zkCallback-261-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21639 INFO (zkCallback-257-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21639 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 21639 INFO (zkCallback-257-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21639 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 21644 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=cloud-managed&newCollection=true&name=managed_coll_shard1_replica_n1&action=CREATE&numShards=2&collection=managed_coll&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=308
[junit4] 2> 21645 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=cloud-managed&newCollection=true&name=managed_coll_shard2_replica_n2&action=CREATE&numShards=2&collection=managed_coll&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=326
[junit4] 2> 21663 INFO (qtp669313105-525) [n:127.0.0.1:45661_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> 21763 INFO (zkCallback-257-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21763 INFO (zkCallback-257-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21764 INFO (zkCallback-261-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21764 INFO (zkCallback-261-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21766 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=cloud-managed&maxShardsPerNode=1&name=managed_coll&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=848
[junit4] 2> 21771 INFO (zkCallback-257-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21774 INFO (zkCallback-261-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/managed_coll/state.json] for collection [managed_coll] has occurred - updating... (live nodes size: [2])
[junit4] 2> 21867 INFO (TEST-ManagedSchemaRoundRobinCloudTest.testAddFieldsRoundRobin-seed#[774AEF7A9828F83A]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddFieldsRoundRobin
[junit4] 2> 21928 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.SchemaManager managed schema loaded . version : 0
[junit4] 2> 21931 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 21938 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 21961 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.ZkController Persisted config data to node /configs/cloud-managed/managed-schema
[junit4] 2> 21961 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 21961 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 21961 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from Zookeeper
[junit4] 2> 21965 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 21968 INFO (Thread-91) [ ] o.a.s.c.SolrCore config update listener called for core managed_coll_shard2_replica_n2
[junit4] 2> 21969 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 21972 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 10 ms
[junit4] 2> 21972 INFO (Thread-92) [ ] o.a.s.c.SolrCore config update listener called for core managed_coll_shard1_replica_n1
[junit4] 2> 21976 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore core reload managed_coll_shard2_replica_n2
[junit4] 2> 21982 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 22000 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.CoreContainer Reloading SolrCore 'managed_coll_shard2_replica_n2' using configuration from configset cloud-managed
[junit4] 2> 22007 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22012 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22058 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45661.solr.core.managed_coll.shard2.replica_n2' (registry 'solr.core.managed_coll.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 22058 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SolrCore [[managed_coll_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node2/managed_coll_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node2/managed_coll_shard2_replica_n2/data/]
[junit4] 2> 22135 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 22135 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 22141 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed
[junit4] 2> 22142 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed
[junit4] 2> 22142 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed/managed-schema
[junit4] 2> 22142 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 22142 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 22143 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677408604574973952
[junit4] 2> 22151 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 22151 INFO (searcherExecutor-299-thread-1-processing-n:127.0.0.1:45661_solr x:managed_coll_shard2_replica_n2 c:managed_coll s:shard2 r:core_node4) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 22156 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 1 from Zookeeper
[junit4] 2> 22156 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr ] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply schema update version 1 for collection managed_coll
[junit4] 2> 22160 INFO (searcherExecutor-299-thread-1-processing-n:127.0.0.1:45661_solr x:managed_coll_shard2_replica_n2 c:managed_coll s:shard2 r:core_node4) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 22160 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22161 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 22165 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22165 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 9 ms
[junit4] 2> 22167 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'managed_coll_shard2_replica_n2' using configuration from configset cloud-managed
[junit4] 2> 22173 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22188 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22206 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.S.Request [managed_coll_shard1_replica_n1] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=1&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 22208 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr ] o.a.s.s.ManagedIndexSchema Took 52.0ms for 1 replicas to apply schema update version 1 for collection managed_coll
[junit4] 2> 22208 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr ] o.a.s.c.S.Request [managed_coll_shard2_replica_n2] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=291
[junit4] 2> 22208 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@49e0df36
[junit4] 2> 22208 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.managed_coll.shard2.replica_n2 tag=SolrCore@49e0df36
[junit4] 2> 22208 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@2e9494aa: rootName = solr_45661, domain = solr.core.managed_coll.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.managed_coll.shard2.replica_n2/com.codahale.metrics.MetricRegistry@1966d8a2
[junit4] 2> 22223 INFO (Thread-91) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45661.solr.core.managed_coll.shard2.replica_n2' (registry 'solr.core.managed_coll.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 22224 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.managed_coll.shard2.leader tag=SolrCore@49e0df36
[junit4] 2> 22224 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore [[managed_coll_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node2/managed_coll_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node2/managed_coll_shard2_replica_n2/data/]
[junit4] 2> 22240 INFO (qtp669313105-527) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.S.Request [managed_coll_shard2_replica_n2] webapp=/solr path=/schema/fields/NumberedField_0 params={wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 22283 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.SchemaManager managed schema loaded . version : 1
[junit4] 2> 22286 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22290 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22291 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 22291 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 22291 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.ZkController Persisted config data to node /configs/cloud-managed/managed-schema
[junit4] 2> 22292 INFO (zkCallback-257-thread-1) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 22292 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 22292 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 2 from Zookeeper
[junit4] 2> 22296 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22300 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22304 INFO (Thread-94) [ ] o.a.s.c.SolrCore config update listener called for core managed_coll_shard2_replica_n2
[junit4] 2> 22307 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed
[junit4] 2> 22308 INFO (zkCallback-261-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 15 ms
[junit4] 2> 22308 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed
[junit4] 2> 22308 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed/managed-schema
[junit4] 2> 22309 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 2 from Zookeeper
[junit4] 2> 22311 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 22311 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore core reload managed_coll_shard2_replica_n2
[junit4] 2> 22312 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22316 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22316 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 7 ms
[junit4] 2> 22316 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 22317 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677408604757426176
[junit4] 2> 22325 INFO (Thread-95) [ ] o.a.s.c.SolrCore config update listener called for core managed_coll_shard1_replica_n1
[junit4] 2> 22326 INFO (Thread-95) [n:127.0.0.1:40697_solr ] o.a.s.c.SolrCore core reload managed_coll_shard1_replica_n1
[junit4] 2> 22329 INFO (searcherExecutor-306-thread-1-processing-n:127.0.0.1:45661_solr) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 22334 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.CoreContainer Reloading SolrCore 'managed_coll_shard1_replica_n1' using configuration from configset cloud-managed
[junit4] 2> 22337 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 22337 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@16dcccdc
[junit4] 2> 22337 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.managed_coll.shard2.replica_n2 tag=SolrCore@16dcccdc
[junit4] 2> 22337 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6b5f35eb: rootName = solr_45661, domain = solr.core.managed_coll.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.managed_coll.shard2.replica_n2/com.codahale.metrics.MetricRegistry@1966d8a2
[junit4] 2> 22340 INFO (searcherExecutor-306-thread-1-processing-n:127.0.0.1:45661_solr) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 22351 INFO (Thread-91) [n:127.0.0.1:45661_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.managed_coll.shard2.leader tag=SolrCore@16dcccdc
[junit4] 2> 22359 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22365 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 22372 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22376 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'managed_coll_shard2_replica_n2' using configuration from configset cloud-managed
[junit4] 2> 22383 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22390 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22417 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40697.solr.core.managed_coll.shard1.replica_n1' (registry 'solr.core.managed_coll.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 22417 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SolrCore [[managed_coll_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node1/managed_coll_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node1/managed_coll_shard1_replica_n1/data/]
[junit4] 2> 22426 INFO (Thread-94) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_45661.solr.core.managed_coll.shard2.replica_n2' (registry 'solr.core.managed_coll.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 22426 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore [[managed_coll_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node2/managed_coll_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node2/managed_coll_shard2_replica_n2/data/]
[junit4] 2> 22492 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 22492 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 22496 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed
[junit4] 2> 22496 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed
[junit4] 2> 22496 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed/managed-schema
[junit4] 2> 22499 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.s.ZkIndexSchemaReader Current schema version 2 is already the latest
[junit4] 2> 22499 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 22499 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677408604948267008
[junit4] 2> 22503 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 22503 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 22504 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 22506 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/cloud-managed
[junit4] 2> 22507 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/cloud-managed
[junit4] 2> 22507 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/cloud-managed/managed-schema
[junit4] 2> 22507 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 2 is already the latest
[junit4] 2> 22507 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 22507 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1677408604956655616
[junit4] 2> 22511 INFO (searcherExecutor-311-thread-1-processing-n:127.0.0.1:40697_solr x:managed_coll_shard1_replica_n1 c:managed_coll s:shard1 r:core_node3) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SolrCore [managed_coll_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 22514 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 22515 INFO (zkCallback-257-thread-1) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 2 from Zookeeper
[junit4] 2> 22516 INFO (searcherExecutor-311-thread-1-processing-n:127.0.0.1:40697_solr x:managed_coll_shard1_replica_n1 c:managed_coll s:shard1 r:core_node3) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.SolrCore [managed_coll_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 22517 INFO (zkCallback-257-thread-1) [ ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22535 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr ] o.a.s.s.ManagedIndexSchema Waiting up to 599 secs for 1 replicas to apply schema update version 2 for collection managed_coll
[junit4] 2> 22552 INFO (searcherExecutor-313-thread-1-processing-n:127.0.0.1:45661_solr) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 22554 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@5234ab46
[junit4] 2> 22554 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.managed_coll.shard2.replica_n2 tag=SolrCore@5234ab46
[junit4] 2> 22554 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@55c92bba: rootName = solr_45661, domain = solr.core.managed_coll.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.managed_coll.shard2.replica_n2/com.codahale.metrics.MetricRegistry@1966d8a2
[junit4] 2> 22560 INFO (searcherExecutor-313-thread-1-processing-n:127.0.0.1:45661_solr) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore [managed_coll_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
[junit4] 2> 22561 INFO (zkCallback-257-thread-1) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22561 INFO (zkCallback-257-thread-1) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 45 ms
[junit4] 2> 22562 INFO (Thread-94) [n:127.0.0.1:45661_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.managed_coll.shard2.leader tag=SolrCore@5234ab46
[junit4] 2> 22580 INFO (Thread-95) [n:127.0.0.1:40697_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 22598 INFO (Thread-95) [n:127.0.0.1:40697_solr ] o.a.s.c.CoreContainer Reloading SolrCore 'managed_coll_shard1_replica_n1' using configuration from configset cloud-managed
[junit4] 2> 22609 INFO (Thread-95) [n:127.0.0.1:40697_solr ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22612 INFO (Thread-95) [n:127.0.0.1:40697_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22623 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.S.Request [managed_coll_shard2_replica_n2] webapp=/solr path=/schema/zkversion params={refreshIfBelowVersion=2&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 22625 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr ] o.a.s.s.ManagedIndexSchema Took 110.0ms for 1 replicas to apply schema update version 2 for collection managed_coll
[junit4] 2> 22625 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr ] o.a.s.c.S.Request [managed_coll_shard1_replica_n1] webapp=/solr path=/schema params={wt=javabin&version=2} status=0 QTime=346
[junit4] 2> 22625 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr ] o.a.s.c.SolrCore [managed_coll_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@4dfd5870
[junit4] 2> 22625 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.managed_coll.shard1.replica_n1 tag=SolrCore@4dfd5870
[junit4] 2> 22625 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6eaa0a41: rootName = solr_40697, domain = solr.core.managed_coll.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.managed_coll.shard1.replica_n1/com.codahale.metrics.MetricRegistry@54d0c245
[junit4] 2> 22650 INFO (qtp1266091378-533) [n:127.0.0.1:40697_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.managed_coll.shard1.leader tag=SolrCore@4dfd5870
[junit4] 2> 22659 INFO (qtp1266091378-556) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.c.S.Request [managed_coll_shard1_replica_n1] webapp=/solr path=/schema/fields/NumberedField_1 params={wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 22660 INFO (Thread-95) [n:127.0.0.1:40697_solr c:managed_coll s:shard1 r:core_node3 x:managed_coll_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40697.solr.core.managed_coll.shard1.replica_n1' (registry 'solr.core.managed_coll.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1c7ef24e
[junit4] 2> 22660 INFO (Thread-95) [n:127.0.0.1:40697_solr ] o.a.s.c.SolrCore [[managed_coll_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node1/managed_coll_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J3/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001/tempDir-001/node1/managed_coll_shard1_replica_n1/data/]
[junit4] 2> 22664 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.SchemaManager managed schema loaded . version : 2
[junit4] 2> 22667 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22670 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22677 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.ZkController Persisted config data to node /configs/cloud-managed/managed-schema
[junit4] 2> 22677 INFO (zkCallback-261-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 22677 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/cloud-managed/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 22679 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 3 from Zookeeper
[junit4] 2> 22682 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22682 INFO (Thread-97) [ ] o.a.s.c.SolrCore config update listener called for core managed_coll_shard2_replica_n2
[junit4] 2> 22683 INFO (Thread-98) [ ] o.a.s.c.SolrCore config update listener called for core managed_coll_shard1_replica_n1
[junit4] 2> 22683 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.7.0
[junit4] 2> 22684 INFO (Thread-98) [n:127.0.0.1:40697_solr ] o.a.s.c.SolrCore core reload managed_coll_shard1_replica_n1
[junit4] 2> 22685 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22685 INFO (zkCallback-257-thread-3) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 6 ms
[junit4] 2> 22687 INFO (Thread-97) [n:127.0.0.1:45661_solr ] o.a.s.c.SolrCore core reload managed_coll_shard2_replica_n2
[junit4] 2> 22688 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.c.CoreContainer Reloading SolrCore 'managed_coll_shard2_replica_n2' using configuration from configset cloud-managed
[junit4] 2> 22691 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
[junit4] 2> 22705 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr c:managed_coll s:shard2 r:core_node4 x:managed_coll_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 22749 INFO (Thread-95) [n:127.0.0.1:40697_solr ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 22749 INFO (Thread-95) [n:127.0.0.1:40697_solr ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 22750 INFO (qtp669313105-525) [n:127.0.0.1:45661_solr
[...truncated too long message...]
ll
[junit4] 2> 9848 INFO (closeThreadPool-153-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72106592173162504-127.0.0.1:34841_solr-n_0000000000) closing
[junit4] 2> 9848 INFO (OverseerStateUpdate-72106592173162504-127.0.0.1:34841_solr-n_0000000000) [n:127.0.0.1:34841_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34841_solr
[junit4] 2> 9849 INFO (OverseerAutoScalingTriggerThread-72106592173162504-127.0.0.1:34841_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 9895 WARN (jetty-launcher-8-thread-1-SendThread(127.0.0.1:35781)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1002c9044720008.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1002c9044720008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1002c9044720008, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 9897 INFO (zkCallback-33-thread-2) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 9909 INFO (closeThreadPool-153-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72106592173162504-127.0.0.1:34841_solr-n_0000000000) closing
[junit4] 2> 9928 WARN (jetty-launcher-8-thread-2-SendThread(127.0.0.1:35781)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1002c9044720007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1002c9044720007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1002c9044720007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:75) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:348) ~[zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1262) [zookeeper-3.6.1.jar:3.6.1]
[junit4] 2> 9996 INFO (jetty-closer-142-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72106592173162504-127.0.0.1:34841_solr-n_0000000000) closing
[junit4] 2> 10002 INFO (jetty-closer-142-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5f9d10b{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 10003 INFO (jetty-closer-142-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2cf631e5{/solr,null,UNAVAILABLE}
[junit4] 2> 10003 INFO (jetty-closer-142-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 10099 INFO (jetty-closer-142-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@52c241df{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
[junit4] 2> 10099 INFO (jetty-closer-142-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@55dc5f09{/solr,null,UNAVAILABLE}
[junit4] 2> 10099 INFO (jetty-closer-142-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 10107 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 10312 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/configs/cloud-managed/managed-schema
[junit4] 2> 12 /solr/configs/cloud-managed
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/collections/managed_coll/terms/shard2
[junit4] 2> 2 /solr/collections/managed_coll/terms/shard1
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 27 /solr/collections/managed_coll/state.json
[junit4] 2> 12 /solr/configs/cloud-managed/managed-schema
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 17 /solr/overseer/queue
[junit4] 2> 8 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 5 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 10319 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:35781
[junit4] 2> 10319 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:35781
[junit4] 2> 10319 INFO (SUITE-ManagedSchemaRoundRobinCloudTest-seed#[774AEF7A9828F83A]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 35781
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-8.x-Linux/solr/build/solr-core/test/J1/temp/solr.schema.ManagedSchemaRoundRobinCloudTest_774AEF7A9828F83A-001
[junit4] 2> Sep 10, 2020 1:57:46 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87), sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=fr-BI, timezone=Asia/Riyadh
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/AdoptOpenJDK 14.0.1 (64-bit)/cpus=16,threads=1,free=383405392,total=516423680
[junit4] 2> NOTE: All tests run in this JVM: [ManagedSchemaRoundRobinCloudTest]
[junit4] Completed [2/5 (2!)] on J1 in 10.49s, 1 test, 1 error <<< FAILURES!
[...truncated 26 lines...]
BUILD FAILED
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1599: The following error occurred while executing this line:
/home/jenkins/workspace/Lucene-Solr-8.x-Linux/lucene/common-build.xml:1126: There were test failures: 5 suites, 5 tests, 2 errors [seed: 774AEF7A9828F83A]
Total time: 15 seconds
[repro] Setting last failure code to 256
[repro] Failures w/original seeds:
[repro] 2/5 failed: org.apache.solr.schema.ManagedSchemaRoundRobinCloudTest
[repro] Exiting with code 256
+ mv lucene/build lucene/build.repro
+ mv solr/build solr/build.repro
+ mv lucene/build.orig lucene/build
+ mv solr/build.orig solr/build
Setting ANT_1_8_2_HOME=/home/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: '/home/jenkins/workspace/Lucene-Solr-8.x-Linux')
[Java] Successfully parsed console log
[Java] -> found 112 issues (skipped 103 duplicates)
[Java] Post processing issues on 'Master' 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 '/home/jenkins/workspace/Lucene-Solr-8.x-Linux'
[Java] -> resolved paths in source directory (53 found, 0 not found)
[Java] Resolving module names from module definitions (build.xml, pom.xml, or Manifest.mf files)
[Java] -> resolved module names for 112 issues
[Java] Resolving package names (or namespaces) by parsing the affected files
[Java] -> resolved package names of 53 affected files
[Java] No filter has been set, publishing all 112 issues
[Java] Creating fingerprints for all affected code blocks to track issues over different builds
[Java] -> created fingerprints for 112 issues (skipped 0 issues)
[Java] Copying affected files to Jenkins' build folder '/home/jenkins/jobs/Lucene-Solr-8.x-Linux/builds/4344/files-with-issues'
[Java] -> 53 copied, 0 not in workspace, 0 not-found, 0 with I/O error
[Java] Using reference build 'Lucene-Solr-8.x-Linux #4343' to compute new, fixed, and outstanding issues
[Java] Issues delta (vs. reference build): outstanding: 112, new: 0, fixed: 0
[Java] No quality gates have been set - skipping
[Java] Health report is disabled - skipping
[Java] Created analysis result for 112 issues (found 0 new issues, fixed 0 issues)
[Java] Attaching ResultAction with ID 'java' to run 'Lucene-Solr-8.x-Linux #4344'.
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Recording test results
Setting ANT_1_8_2_HOME=/home/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=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2
Setting ANT_1_8_2_HOME=/home/jenkins/tools/hudson.tasks.Ant_AntInstallation/ANT_1.8.2