You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/04/18 09:23:13 UTC
[JENKINS] Lucene » Lucene-Solr-BadApples-Tests-8.x - Build # 251 - Still Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-BadApples-Tests-8.x/251/
1 tests failed.
FAILED: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory
Error Message:
action did not execute
Stack Trace:
java.lang.AssertionError: action did not execute
at __randomizedtesting.SeedInfo.seed([225266B80C01D258:4FAEC245B6492D5F]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest.testHistory(AutoscalingHistoryHandlerTest.java:285)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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 15532 lines...]
[junit4] Suite: org.apache.solr.handler.admin.AutoscalingHistoryHandlerTest
[junit4] 2> 1937830 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 1937830 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 1937831 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_225266B80C01D258-001/data-dir-134-001
[junit4] 2> 1937831 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=16 numCloses=16
[junit4] 2> 1937831 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 1937833 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 1937833 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.SolrCloudTestCase Using per-replica state
[junit4] 2> 1937833 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_225266B80C01D258-001/tempDir-001
[junit4] 2> 1937833 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 1937834 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 1937834 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 1937835 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 1937934 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 40061
[junit4] 2> 1937934 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:40061
[junit4] 2> 1937934 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:40061
[junit4] 2> 1937934 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 40061
[junit4] 2> 1937936 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1937945 INFO (zkConnectionManagerCallback-22969-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1937945 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1937990 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1937994 INFO (zkConnectionManagerCallback-22971-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1937994 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1937996 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1938003 INFO (zkConnectionManagerCallback-22973-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1938003 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1938112 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1938112 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1938112 INFO (jetty-launcher-22974-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1938120 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 1938120 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 1938120 INFO (jetty-launcher-22974-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 1938171 INFO (jetty-launcher-22974-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1938171 INFO (jetty-launcher-22974-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1938171 INFO (jetty-launcher-22974-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1938171 INFO (jetty-launcher-22974-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@36a3bb1a{/solr,null,AVAILABLE}
[junit4] 2> 1938173 INFO (jetty-launcher-22974-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 1938173 INFO (jetty-launcher-22974-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 1938173 INFO (jetty-launcher-22974-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 1938173 INFO (jetty-launcher-22974-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@14a3693{/solr,null,AVAILABLE}
[junit4] 2> 1938173 INFO (jetty-launcher-22974-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5da10696{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:35081}
[junit4] 2> 1938173 INFO (jetty-launcher-22974-thread-1) [ ] o.e.j.s.Server Started @1938200ms
[junit4] 2> 1938173 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:40061/solr, hostPort=35081}
[junit4] 2> 1938173 ERROR (jetty-launcher-22974-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1938173 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1938173 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 1938174 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1938174 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 1938174 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-04-18T08:54:01.033Z
[junit4] 2> 1938174 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1938175 INFO (jetty-launcher-22974-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@bec4cb6{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:37297}
[junit4] 2> 1938175 INFO (jetty-launcher-22974-thread-2) [ ] o.e.j.s.Server Started @1938202ms
[junit4] 2> 1938175 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:40061/solr, hostPort=37297}
[junit4] 2> 1938175 ERROR (jetty-launcher-22974-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 1938175 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 1938175 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 1938175 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 1938175 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 1938175 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-04-18T08:54:01.034Z
[junit4] 2> 1938176 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1938176 INFO (zkConnectionManagerCallback-22976-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1938176 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1938176 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1938177 INFO (zkConnectionManagerCallback-22978-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1938177 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1938180 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 1938184 WARN (jetty-launcher-22974-thread-1-SendThread(127.0.0.1:40061)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1049b1cf0310003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1938185 WARN (jetty-launcher-22974-thread-2-SendThread(127.0.0.1:40061)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1049b1cf0310004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1939424 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1939428 WARN (jetty-launcher-22974-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@6e55496a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1939428 WARN (jetty-launcher-22974-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@6e55496a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1939432 WARN (jetty-launcher-22974-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7caf794b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1939432 WARN (jetty-launcher-22974-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7caf794b[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1939434 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40061/solr
[junit4] 2> 1939443 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1939450 INFO (zkConnectionManagerCallback-22992-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1939450 INFO (jetty-launcher-22974-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1939460 WARN (jetty-launcher-22974-thread-1-SendThread(127.0.0.1:40061)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1049b1cf0310005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1939684 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1939687 INFO (zkConnectionManagerCallback-22994-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1939687 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1939754 WARN (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 1939761 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35081_solr
[junit4] 2> 1939761 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer Overseer (id=73354042535903238-127.0.0.1:35081_solr-n_0000000000) starting
[junit4] 2> 1939768 INFO (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:35081_solr
[junit4] 2> 1939769 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35081_solr
[junit4] 2> 1939770 DEBUG (OverseerAutoScalingTriggerThread-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 1939772 DEBUG (OverseerAutoScalingTriggerThread-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 1939772 DEBUG (OverseerAutoScalingTriggerThread-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 1939772 DEBUG (OverseerAutoScalingTriggerThread-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 1939774 INFO (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1939775 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1939775 WARN (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_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> 1939789 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1939790 DEBUG (OverseerAutoScalingTriggerThread-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:35081_solr]
[junit4] 2> 1939799 DEBUG (OverseerAutoScalingTriggerThread-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 1939800 DEBUG (OverseerAutoScalingTriggerThread-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 1939800 DEBUG (ScheduledTrigger-22999-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 1939818 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35081.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7e5f7c79
[junit4] 2> 1939826 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35081.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7e5f7c79
[junit4] 2> 1939826 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35081.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7e5f7c79
[junit4] 2> 1939827 INFO (jetty-launcher-22974-thread-1) [n:127.0.0.1:35081_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_225266B80C01D258-001/tempDir-001/node1
[junit4] 2> 1940324 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 1940329 WARN (jetty-launcher-22974-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3a8600f4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1940329 WARN (jetty-launcher-22974-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3a8600f4[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1940346 WARN (jetty-launcher-22974-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@20d9337a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1940346 WARN (jetty-launcher-22974-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@20d9337a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 1940347 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:40061/solr
[junit4] 2> 1940354 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1940354 INFO (zkConnectionManagerCallback-23014-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1940354 INFO (jetty-launcher-22974-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1940355 WARN (jetty-launcher-22974-thread-2-SendThread(127.0.0.1:40061)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1049b1cf0310007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 1940456 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1940457 INFO (zkConnectionManagerCallback-23016-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1940457 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1940465 WARN (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 1940474 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 1940478 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:37297_solr as DOWN
[junit4] 2> 1940479 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1940479 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37297_solr
[junit4] 2> 1940480 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "operation":"downnode"} current state version: 0
[junit4] 2> 1940480 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:37297_solr
[junit4] 2> 1940480 INFO (zkCallback-22993-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1940482 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 1940482 WARN (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_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> 1940483 INFO (zkCallback-23015-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 1940493 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 1940520 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37297.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7e5f7c79
[junit4] 2> 1940529 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37297.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7e5f7c79
[junit4] 2> 1940529 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_37297.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7e5f7c79
[junit4] 2> 1940530 INFO (jetty-launcher-22974-thread-2) [n:127.0.0.1:37297_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_225266B80C01D258-001/tempDir-001/node2
[junit4] 2> 1940551 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 1940554 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 1940554 INFO (zkConnectionManagerCallback-23029-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 1940554 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 1940556 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 1940558 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:40061/solr ready
[junit4] 2> 1940692 INFO (OverseerThreadFactory-23001-thread-1-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 1940695 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":".system",
[junit4] 2> "fromApi":"true",
[junit4] 2> "numShards":1,
[junit4] 2> "createNodeSet":"127.0.0.1:35081_solr",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1940695 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: .system
[junit4] 2> 1940695 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/.system/state.json
[junit4] 2> 1940798 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1940798 INFO (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1940801 DEBUG (ScheduledTrigger-22999-thread-2) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 1
[junit4] 2> 1940802 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1940802 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr"}}}
[junit4] 2> 1940902 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 0
[junit4] 2> 1941001 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1941001 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 1941005 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1941005 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1941005 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1941005 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 1941011 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 1941017 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 1941019 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 1941019 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 1941028 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 1941037 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 1941039 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from configset .system, trusted=true
[junit4] 2> 1941040 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35081.solr.core..system.shard1.replica_n1' (registry 'solr.core..system.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@7e5f7c79
[junit4] 2> 1941040 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_225266B80C01D258-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_225266B80C01D258-001/tempDir-001/node1/.system_shard1_replica_n1/data/]
[junit4] 2> 1941087 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 1941087 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 1941088 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 1941088 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 1941090 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 1941091 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 1941091 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 1941091 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 1941092 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 1941092 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1697367766021963776
[junit4] 2> 1941097 INFO (searcherExecutor-23031-thread-1-processing-n:127.0.0.1:35081_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 1941099 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 1941099 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 1941103 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 1941103 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 1941103 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:35081/solr/.system_shard1_replica_n1/
[junit4] 2> 1941103 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 1941104 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:35081/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 1941104 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/73354042535903238-core_node2-n_0000000000
[junit4] 2> 1941241 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 1
[junit4] 2> 1941250 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:35081/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 1941252 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "state":"active",
[junit4] 2> "operation":"leader"} current state version: 0
[junit4] 2> 1941352 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 2
[junit4] 2> 1941353 INFO (zkCallback-22993-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1941354 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 1941355 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1941355 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":".system",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1941355 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}
[junit4] 2> 1941355 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(.system//collections/.system/state.json/3 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:35081_solr",
[junit4] 2> "base_url":"http://127.0.0.1:35081/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 1941357 INFO (qtp1399251594-39673) [n:127.0.0.1:35081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=356
[junit4] 2> 1941359 INFO (qtp1399251594-39671) [n:127.0.0.1:35081_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> 1941456 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/.system/state.json version: 3
[junit4] 2> 1941456 INFO (zkCallback-22993-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1941456 INFO (zkCallback-22993-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [2])
[junit4] 2> 1941458 INFO (qtp1399251594-39671) [n:127.0.0.1:35081_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=.system&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:35081_solr&wt=javabin&version=2} status=0 QTime=786
[junit4] 2> 1941458 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 1941476 INFO (OverseerCollectionConfigSetProcessor-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 1941477 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1941477 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED
[junit4] 2> 1941478 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/protwords.txt
[junit4] 2> 1941480 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2> 1941481 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/solrconfig.xml
[junit4] 2> 1941483 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 1941485 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 1941486 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 1941487 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 1941489 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 1941490 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 1941491 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 1941494 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 1941495 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 1941497 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 1941498 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 1941501 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 1941505 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 1941512 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 1941516 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 1941518 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 1941520 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 1941521 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 1941523 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 1941524 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 1941525 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 1941526 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 1941528 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 1941529 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 1941530 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 1941532 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 1941533 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 1941534 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 1941535 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 1941537 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 1941538 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 1941542 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 1941544 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 1941545 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 1941546 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 1941549 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 1941554 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 1941557 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 1941562 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 1941566 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/synonyms.txt
[junit4] 2> 1941569 INFO (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/stopwords.txt
[junit4] 2> 1941580 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "name":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "fromApi":"true",
[junit4] 2> "numShards":"1",
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "createNodeSet":"127.0.0.1:37297_solr",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "stateFormat":"2",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "operation":"create"} current state version: 0
[junit4] 2> 1941580 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: AutoscalingHistoryHandlerTest_collection
[junit4] 2> 1941681 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 1941782 WARN (OverseerThreadFactory-23001-thread-2-processing-n:127.0.0.1:35081_solr) [n:127.0.0.1:35081_solr ] o.a.s.c.a.c.CreateCollectionCmd Specified number of replicas of 3 on collection AutoscalingHistoryHandlerTest_collection is higher than the number of Solr instances currently live or live and part of your createNodeSet(1). It's unusual to run two replica of the same slice on the same Solr-instance.
[junit4] 2> 1941785 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1941785 INFO (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1941786 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 1941786 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"}}}
[junit4] 2> 1941787 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1941787 INFO (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1941788 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"}}}
[junit4] 2> 1941788 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"}}}
[junit4] 2> 1941789 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"} current state version: 0
[junit4] 2> 1941789 INFO (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 1941790 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"}}}
[junit4] 2> 1941790 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"}}}
[junit4] 2> 1941804 DEBUG (ScheduledTrigger-22999-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 2 and last live nodes: 2
[junit4] 2> 1941891 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/AutoscalingHistoryHandlerTest_collection/state.json version: 0
[junit4] 2> 1941995 INFO (qtp86745699-39679) [n:127.0.0.1:37297_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node5&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n2&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1941995 INFO (qtp86745699-39676) [n:127.0.0.1:37297_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n4 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node6&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n4&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1941996 INFO (qtp86745699-39677) [n:127.0.0.1:37297_solr x:AutoscalingHistoryHandlerTest_collection_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=AutoscalingHistoryHandlerTest_collection.AUTOCREATED&newCollection=true&name=AutoscalingHistoryHandlerTest_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=AutoscalingHistoryHandlerTest_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 1942000 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 1, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1942000 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "core_node_name":"core_node5",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1942000 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node5:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1942000 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"}}}}}
[junit4] 2> 1942000 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 2, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1942001 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node3",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 1942001 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node3:{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 1942001 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(AutoscalingHistoryHandlerTest_collection//collections/AutoscalingHistoryHandlerTest_collection/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"3",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"3",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"3",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node3":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node5":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n2",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node6":{
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr"}}}}}
[junit4] 2> 1942005 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.Overseer processMessage: queueSize: 3, message = {
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"AutoscalingHistoryHandlerTest_collection",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 1942006 DEBUG (OverseerStateUpdate-73354042535903238-127.0.0.1:35081_solr-n_0000000000) [n:127.0.0.1:35081_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"AutoscalingHistoryHandlerTest_collection_shard1_replica_n4",
[junit4] 2> "core_node_name":"core_node6",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:37297_solr",
[junit4] 2> "base_url":"http://127.0.0.1:37297/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"dow
[...truncated too long message...]
ServerConnector@396c9ff9{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2006793 INFO (jetty-closer-23081-thread-3) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@522f1060{/solr,null,STOPPED}
[junit4] 2> 2006793 INFO (jetty-closer-23081-thread-3) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2006794 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2006794 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker-SendThread(127.0.0.1:40061)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1049b1cf0310000.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310000, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310000, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 2006895 WARN (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker-SendThread(127.0.0.1:40061)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x1049b1cf0310001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x1049b1cf0310001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 2006997 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> 5 /solr/clusterprops.json
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 4 /solr/collections/AutoscalingHistoryHandlerTest_collection/terms/shard1
[junit4] 2> 3 /solr/packages.json
[junit4] 2> 3 /solr/security.json
[junit4] 2> 3 /solr/collections/.system/terms/shard1
[junit4] 2> 3 /solr/configs/AutoscalingHistoryHandlerTest_collection.AUTOCREATED/managed-schema
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 21 /solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 12 /solr/autoscaling.json
[junit4] 2> 11 /solr/collections/.system/state.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 38 /solr/overseer/queue
[junit4] 2> 17 /solr/live_nodes
[junit4] 2> 11 /solr/collections
[junit4] 2> 9 /solr/collections/AutoscalingHistoryHandlerTest_collection/state.json
[junit4] 2> 7 /solr/overseer/collection-queue-work
[junit4] 2> 6 /solr/collections/.system/state.json
[junit4] 2>
[junit4] 2> 2007000 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:40061
[junit4] 2> 2007000 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:40061
[junit4] 2> 2007000 INFO (SUITE-AutoscalingHistoryHandlerTest-seed#[225266B80C01D258]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 40061
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.handler.admin.AutoscalingHistoryHandlerTest_225266B80C01D258-001
[junit4] 2> Apr 18, 2021 8:55:09 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): {action_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), event.property.preferredOperation_ss=Lucene84, event.source_s=FST50, event.property.replicaType_ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), type=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), before.actions_ss=FST50, event.property._enqueue_time__ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), error.details_t=FST50, stage_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), event.id_s=PostingsFormat(name=MockRandom), event.property.eventTimes_ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), source_s=PostingsFormat(name=MockRandom), event.type_s=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), id=PostingsFormat(name=MockRandom), event.property.nodeNames_ss=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84)), error.message_t=TestBloomFilteredLucenePostings(BloomFilteringPostingsFormat(Lucene84))}, docValues:{_version_=DocValuesFormat(name=Asserting), event.time_l=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=67, maxMBSortInHeap=5.054743191593094, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=ms-MY, timezone=W-SU
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=52332232,total=517472256
[junit4] 2> NOTE: All tests run in this JVM: [TestCloudPseudoReturnFields, TestCloudNestedDocsSort, TestCoreContainer, TestJoin, SolrGraphiteReporterTest, TestInPlaceUpdatesDistrib, TimeZoneUtilsTest, TestInfoStreamLogging, CustomCollectionTest, TestZkChroot, TestTolerantUpdateProcessorRandomCloud, DeleteReplicaTest, TestClusterProperties, TestExactSharedStatsCache, PreAnalyzedUpdateProcessorTest, FieldAnalysisRequestHandlerTest, TestStressVersions, TestBM25SimilarityFactory, TestDynamicLoading, DistributedIntervalFacetingTest, TestLogWatcher, TestReversedWildcardFilterFactory, TestCloudDeduplication, HdfsRecoverLeaseTest, AuditLoggerPluginTest, TestFileDictionaryLookup, TestHashPartitioner, ManagedSchemaRoundRobinCloudTest, HdfsDirectoryTest, TestCloudJSONFacetSKGEquiv, TestBackupRepositoryFactory, TestEmbeddedSolrServerAdminHandler, DirectoryFactoriesTest, ResponseHeaderTest, BigEndianAscendingWordSerializerTest, PluginInfoTest, TestMultiValuedNumericRangeQuery, SortByFunctionTest, TestSolrXml, ZkStateWriterTest, UpdateParamsTest, SyncSliceTest, SearchHandlerTest, CreateRoutedAliasTest, TestStressLucene, TestNestedUpdateProcessor, TestCrossCoreJoin, TestBulkSchemaConcurrent, TestSimUtils, TestMergePolicyConfig, TestPivotHelperCode, TestAddFieldRealTimeGet, MetricTriggerIntegrationTest, TestSimComputePlanAction, HttpTriggerListenerTest, HdfsBackupRepositoryIntegrationTest, AssignBackwardCompatibilityTest, RequestLoggingTest, TestManagedSynonymGraphFilterFactory, NestedShardedAtomicUpdateTest, DistributedQueryComponentOptimizationTest, OverseerSolrResponseUnsafeSerializationTest, TestPackages, TestCloudDeleteByQuery, TestSolrConfigHandler, HttpPartitionOnCommitTest, TestRebalanceLeaders, CoreAdminHandlerTest, TestSolrCloudWithKerberosAlt, CollectionsAPISolrJTest, ClusterStateTest, SampleTest, TestDistribPackageStore, RankFieldTest, IgnoreLargeDocumentProcessorFactoryTest, TestPhraseSuggestions, TestGraphTermsQParserPlugin, TestLFUCache, TestImpersonationWithHadoopAuth, TestBulkSchemaAPI, TestFacetMethods, ResourceLoaderTest, WrapperMergePolicyFactoryTest, DynamicMapsTest, TestDFRSimilarityFactory, TestSystemCollAutoCreate, TestSolrCoreProperties, CdcrUpdateLogTest, TestStressLiveNodes, RoutingToNodesWithPropertiesTest, ChaosMonkeyNothingIsSafeTest, TestManagedSchemaThreadSafety, TestSolrConfigHandlerCloud, TestRangeQuery, TestShortCircuitedRequests, JWTAuthPluginIntegrationTest, TestJavabinTupleStreamParser, HLLSerializationTest, DistributedUpdateProcessorTest, CustomTermsComponentTest, TestConfigSetsAPIZkFailure, UniqFieldsUpdateProcessorFactoryTest, PackageManagerCLITest, TestAtomicUpdateErrorCases, ScheduledMaintenanceTriggerTest, LoggingHandlerTest, TestSearcherReuse, LargeFieldTest, TestSchemaNameResource, TestRawTransformer, TestRecoveryHdfs, TimeRoutedAliasUpdateProcessorTest, DateRangeFieldTest, SolrCLIZkUtilsTest, TaggingAttributeTest, ReindexCollectionTest, ShowFileRequestHandlerTest, PurgeGraphTest, ShardSplitTest, SearchRateTriggerIntegrationTest, SearchRateTriggerTest, TriggerEventQueueTest, TriggerIntegrationTest, ZkDynamicConfigTest, AlternateDirectoryTest, BlobRepositoryCloudTest, ByteBuffersDirectoryFactoryTest, ConfigureRecoveryStrategyTest, TestConfig, TestConfigOverlay, TestConfigSetProperties, TestQuerySenderListener, TestQuerySenderNoQuery, TestReloadAndDeleteDocs, TestCSVLoader, TestContainerPlugin, TestRestoreCore, AutoscalingHistoryHandlerTest]
[junit4] Completed [666/936 (1!)] on J1 in 70.02s, 1 test, 1 failure <<< FAILURES!
[...truncated 51742 lines...]
[JENKINS] Lucene » Lucene-Solr-BadApples-Tests-8.x - Build # 252 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-BadApples-Tests-8.x/252/
2 tests failed.
FAILED: org.apache.solr.cloud.TestWithCollection.testNodeAdded
Error Message:
Action was not fired till 30 seconds
Stack Trace:
java.lang.AssertionError: Action was not fired till 30 seconds
at __randomizedtesting.SeedInfo.seed([631D9F6D63361824:6DEC91AC195B027]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.apache.solr.cloud.TestWithCollection.testNodeAdded(TestWithCollection.java:472)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: org.apache.solr.metrics.reporters.solr.SolrCloudReportersTest.testDefaultPlugins
Error Message:
Timeout occurred while waiting response from server at: https://127.0.0.1:34423/solr
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occurred while waiting response from server at: https://127.0.0.1:34423/solr
at __randomizedtesting.SeedInfo.seed([631D9F6D63361824:2B9DCDBD3F763B09]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:692)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:266)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:248)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:369)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:297)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1171)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:934)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:866)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:214)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:231)
at org.apache.solr.metrics.reporters.solr.SolrCloudReportersTest.testDefaultPlugins(SolrCloudReportersTest.java:176)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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.junit.rules.RunRules.evaluate(RunRules.java:20)
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 org.junit.rules.RunRules.evaluate(RunRules.java:20)
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)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:475)
at sun.security.ssl.SSLSocketInputRecord.readHeader(SSLSocketInputRecord.java:469)
at sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:69)
at sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1228)
at sun.security.ssl.SSLSocketImpl.access$300(SSLSocketImpl.java:75)
at sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:915)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:110)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:571)
... 53 more
Build Log:
[...truncated 14398 lines...]
[junit4] Suite: org.apache.solr.cloud.TestWithCollection
[junit4] 2> 763507 INFO (SUITE-TestWithCollection-seed#[631D9F6D63361824]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 763508 INFO (SUITE-TestWithCollection-seed#[631D9F6D63361824]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_631D9F6D63361824-001/data-dir-124-001
[junit4] 2> 763508 WARN (SUITE-TestWithCollection-seed#[631D9F6D63361824]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 763508 INFO (SUITE-TestWithCollection-seed#[631D9F6D63361824]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 763509 INFO (SUITE-TestWithCollection-seed#[631D9F6D63361824]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 763509 INFO (SUITE-TestWithCollection-seed#[631D9F6D63361824]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 763512 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddReplicaWithPolicy
[junit4] 2> 763512 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_631D9F6D63361824-001/tempDir-001
[junit4] 2> 763512 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 763513 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 763513 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 763513 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 763613 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.ZkTestServer start zk server on port: 41793
[junit4] 2> 763613 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:41793
[junit4] 2> 763613 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:41793
[junit4] 2> 763613 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 41793
[junit4] 2> 763614 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 763619 INFO (zkConnectionManagerCallback-7633-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 763619 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 763632 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 763633 INFO (zkConnectionManagerCallback-7635-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 763633 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 763634 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 763636 INFO (zkConnectionManagerCallback-7637-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 763636 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 763739 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 763739 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 763739 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 763739 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 763739 INFO (jetty-launcher-7638-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 763739 INFO (jetty-launcher-7638-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_281-b09
[junit4] 2> 763744 INFO (jetty-launcher-7638-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 763744 INFO (jetty-launcher-7638-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 763744 INFO (jetty-launcher-7638-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 763748 INFO (jetty-launcher-7638-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@167b8b06{/solr,null,AVAILABLE}
[junit4] 2> 763749 INFO (jetty-launcher-7638-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 763749 INFO (jetty-launcher-7638-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 763749 INFO (jetty-launcher-7638-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 763749 INFO (jetty-launcher-7638-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2b4b60ab{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:34125}
[junit4] 2> 763749 INFO (jetty-launcher-7638-thread-1) [ ] o.e.j.s.Server Started @763777ms
[junit4] 2> 763749 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:41793/solr, hostPort=34125}
[junit4] 2> 763749 ERROR (jetty-launcher-7638-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 763750 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 763750 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 763750 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 763750 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 763750 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-04-19T09:07:00.062Z
[junit4] 2> 763752 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 763752 INFO (jetty-launcher-7638-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@4ccc65b3{/solr,null,AVAILABLE}
[junit4] 2> 763752 INFO (jetty-launcher-7638-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@376f1389{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:44679}
[junit4] 2> 763752 INFO (jetty-launcher-7638-thread-2) [ ] o.e.j.s.Server Started @763781ms
[junit4] 2> 763752 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:41793/solr, hostPort=44679}
[junit4] 2> 763753 ERROR (jetty-launcher-7638-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 763753 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 763753 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.9.0
[junit4] 2> 763753 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 763753 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr
[junit4] 2> 763753 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-04-19T09:07:00.065Z
[junit4] 2> 763754 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 763756 INFO (zkConnectionManagerCallback-7640-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 763756 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 763756 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 763760 INFO (zkConnectionManagerCallback-7642-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 763760 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 763760 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 763764 WARN (jetty-launcher-7638-thread-2-SendThread(127.0.0.1:41793)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x104a04cfcb20004.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x104a04cfcb20004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x104a04cfcb20004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 763764 WARN (jetty-launcher-7638-thread-1-SendThread(127.0.0.1:41793)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x104a04cfcb20003.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x104a04cfcb20003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x104a04cfcb20003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 764094 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 764095 WARN (jetty-launcher-7638-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@58bf77a6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 764095 WARN (jetty-launcher-7638-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@58bf77a6[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 764097 WARN (jetty-launcher-7638-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@7fd563cd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 764097 WARN (jetty-launcher-7638-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@7fd563cd[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 764098 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41793/solr
[junit4] 2> 764099 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 764100 INFO (zkConnectionManagerCallback-7656-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 764100 INFO (jetty-launcher-7638-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 764101 WARN (jetty-launcher-7638-thread-1-SendThread(127.0.0.1:41793)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x104a04cfcb20005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x104a04cfcb20005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x104a04cfcb20005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 764202 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 764203 INFO (zkConnectionManagerCallback-7658-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 764203 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 764250 WARN (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 764254 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34125_solr
[junit4] 2> 764254 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.c.Overseer Overseer (id=73359746462253062-127.0.0.1:34125_solr-n_0000000000) starting
[junit4] 2> 764258 INFO (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:34125_solr
[junit4] 2> 764260 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34125_solr
[junit4] 2> 764261 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 764262 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 764262 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 764262 WARN (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_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> 764268 INFO (zkCallback-7657-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 764268 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 764268 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 764274 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:34125_solr]
[junit4] 2> 764280 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 764280 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 764280 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 764281 DEBUG (ScheduledTrigger-7663-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 764281 TRACE (ScheduledTrigger-7663-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Current Live Nodes for .auto_add_replicas: [127.0.0.1:34125_solr]
[junit4] 2> 764281 TRACE (ScheduledTrigger-7663-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Last Live Nodes for .auto_add_replicas: [127.0.0.1:34125_solr]
[junit4] 2> 764306 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34125.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3118047a
[junit4] 2> 764313 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34125.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3118047a
[junit4] 2> 764313 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34125.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3118047a
[junit4] 2> 764314 INFO (jetty-launcher-7638-thread-1) [n:127.0.0.1:34125_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_631D9F6D63361824-001/tempDir-001/node1
[junit4] 2> 764479 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 764480 WARN (jetty-launcher-7638-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@1a6c4290[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 764480 WARN (jetty-launcher-7638-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@1a6c4290[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 764482 WARN (jetty-launcher-7638-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2b0abe19[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 764482 WARN (jetty-launcher-7638-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2b0abe19[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 764483 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:41793/solr
[junit4] 2> 764483 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 764488 INFO (zkConnectionManagerCallback-7678-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 764488 INFO (jetty-launcher-7638-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 764590 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 764590 INFO (zkConnectionManagerCallback-7680-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 764590 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 764594 WARN (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 764595 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 764598 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:44679_solr as DOWN
[junit4] 2> 764598 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 764598 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44679_solr
[junit4] 2> 764599 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:44679_solr
[junit4] 2> 764599 INFO (zkCallback-7657-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 764600 INFO (zkCallback-7679-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 764600 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 764601 WARN (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_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> 764610 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 764628 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44679.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3118047a
[junit4] 2> 764647 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44679.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3118047a
[junit4] 2> 764647 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44679.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3118047a
[junit4] 2> 764648 INFO (jetty-launcher-7638-thread-2) [n:127.0.0.1:44679_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_631D9F6D63361824-001/tempDir-001/node2
[junit4] 2> 764663 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 764663 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 764666 INFO (zkConnectionManagerCallback-7693-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 764666 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 764668 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 764668 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:41793/solr ready
[junit4] 2> 764671 DEBUG (zkCallback-7657-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 764671 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 764674 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 764675 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 764691 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 764692 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 764693 INFO (qtp967375564-11158) [n:127.0.0.1:44679_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 764696 DEBUG (qtp967375564-11155) [n:127.0.0.1:44679_solr ] o.a.s.c.a.AutoScalingHandler Verified autoscaling configuration
[junit4] 2> 764697 INFO (qtp967375564-11155) [n:127.0.0.1:44679_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/cluster/autoscaling params={wt=javabin&version=2} status=0 QTime=17
[junit4] 2> 764697 INFO (TEST-TestWithCollection.testAddReplicaWithPolicy-seed#[631D9F6D63361824]) [ ] o.a.s.c.TestWithCollection Chosen node 127.0.0.1:34125_solr for collection testAddReplicaWithPolicy_abc
[junit4] 2> 764698 DEBUG (zkCallback-7657-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 3
[junit4] 2> 764698 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 3
[junit4] 2> 764698 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 764699 DEBUG (OverseerAutoScalingTriggerThread-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 3, lastZnodeVersion 3
[junit4] 2> 764700 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testAddReplicaWithPolicy_abc
[junit4] 2> 764701 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED
[junit4] 2> 764701 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/protwords.txt
[junit4] 2> 764702 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/managed-schema
[junit4] 2> 764704 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/solrconfig.xml
[junit4] 2> 764705 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 764707 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 764708 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 764709 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 764710 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 764712 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 764713 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 764714 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 764715 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 764716 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 764718 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 764719 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 764720 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 764721 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 764723 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 764724 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 764725 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 764726 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 764727 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 764729 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 764730 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 764731 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 764732 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 764733 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 764734 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 764736 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 764737 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 764740 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 764742 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 764743 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 764744 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 764745 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 764746 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 764747 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 764749 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 764750 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 764751 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 764752 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 764753 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 764755 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/synonyms.txt
[junit4] 2> 764756 INFO (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/stopwords.txt
[junit4] 2> 764759 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testAddReplicaWithPolicy_abc
[junit4] 2> 764759 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testAddReplicaWithPolicy_abc/state.json
[junit4] 2> 764866 DEBUG (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.s.c.a.PolicyHelper Creating a new session
[junit4] 2> 764868 INFO (qtp629971562-11150) [n:127.0.0.1:34125_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 764870 INFO (qtp967375564-11148) [n:127.0.0.1:44679_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/metrics params={prefix=CONTAINER.fs.usableSpace,CONTAINER.fs.totalSpace,CONTAINER.cores&wt=javabin&version=2&group=solr.node} status=0 QTime=0
[junit4] 2> 764870 DEBUG (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.s.c.a.PolicyHelper New session created, 19869270526015834
[junit4] 2> 764875 DEBUG (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.s.c.a.PolicyHelper returnSession 19869270526015834
[junit4] 2> 764877 INFO (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:34125_solr",
[junit4] 2> "base_url":"http://127.0.0.1:34125/solr",
[junit4] 2> "collection":"testAddReplicaWithPolicy_abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 764878 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 764878 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:34125_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "base_url":"http://127.0.0.1:34125/solr"}}}
[junit4] 2> 764978 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplicaWithPolicy_abc/state.json version: 0
[junit4] 2> 765080 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testAddReplicaWithPolicy_abc.AUTOCREATED&newCollection=true&name=testAddReplicaWithPolicy_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testAddReplicaWithPolicy_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 765084 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:34125_solr",
[junit4] 2> "base_url":"http://127.0.0.1:34125/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testAddReplicaWithPolicy_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 765084 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:34125_solr",
[junit4] 2> "base_url":"http://127.0.0.1:34125/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}
[junit4] 2> 765084 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testAddReplicaWithPolicy_abc//collections/testAddReplicaWithPolicy_abc/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:34125_solr",
[junit4] 2> "base_url":"http://127.0.0.1:34125/solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 765094 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.9.0
[junit4] 2> 765114 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 765148 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 765175 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testAddReplicaWithPolicy_abc_shard1_replica_n1' using configuration from configset testAddReplicaWithPolicy_abc.AUTOCREATED, trusted=true
[junit4] 2> 765175 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_34125.solr.core.testAddReplicaWithPolicy_abc.shard1.replica_n1' (registry 'solr.core.testAddReplicaWithPolicy_abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@3118047a
[junit4] 2> 765175 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [[testAddReplicaWithPolicy_abc_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_631D9F6D63361824-001/tempDir-001/node1/testAddReplicaWithPolicy_abc_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_631D9F6D63361824-001/tempDir-001/node1/testAddReplicaWithPolicy_abc_shard1_replica_n1/data/]
[junit4] 2> 765184 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplicaWithPolicy_abc/state.json version: 1
[junit4] 2> 765229 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 765229 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 765231 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 765231 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 765233 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testAddReplicaWithPolicy_abc.AUTOCREATED
[junit4] 2> 765233 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testAddReplicaWithPolicy_abc.AUTOCREATED
[junit4] 2> 765233 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testAddReplicaWithPolicy_abc.AUTOCREATED/managed-schema
[junit4] 2> 765233 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 765234 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 765234 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 765235 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 765235 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1697459178356867072
[junit4] 2> 765237 INFO (searcherExecutor-7695-thread-1-processing-n:127.0.0.1:34125_solr x:testAddReplicaWithPolicy_abc_shard1_replica_n1 c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 765237 INFO (searcherExecutor-7695-thread-1-processing-n:127.0.0.1:34125_solr x:testAddReplicaWithPolicy_abc_shard1_replica_n1 c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 765238 INFO (searcherExecutor-7695-thread-1-processing-n:127.0.0.1:34125_solr x:testAddReplicaWithPolicy_abc_shard1_replica_n1 c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [testAddReplicaWithPolicy_abc_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 765241 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testAddReplicaWithPolicy_abc/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 765241 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testAddReplicaWithPolicy_abc/leaders/shard1
[junit4] 2> 765243 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 765243 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 765243 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34125/solr/testAddReplicaWithPolicy_abc_shard1_replica_n1/
[junit4] 2> 765244 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 765244 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:34125/solr/testAddReplicaWithPolicy_abc_shard1_replica_n1/ has no replicas
[junit4] 2> 765244 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testAddReplicaWithPolicy_abc/leaders/shard1/leader after winning as /collections/testAddReplicaWithPolicy_abc/leader_elect/shard1/election/73359746462253062-core_node2-n_0000000000
[junit4] 2> 765246 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34125/solr/testAddReplicaWithPolicy_abc_shard1_replica_n1/ shard1
[junit4] 2> 765347 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplicaWithPolicy_abc/state.json version: 2
[junit4] 2> 765347 INFO (zkCallback-7657-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplicaWithPolicy_abc/state.json] for collection [testAddReplicaWithPolicy_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 765348 INFO (zkCallback-7657-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplicaWithPolicy_abc/state.json] for collection [testAddReplicaWithPolicy_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 765349 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr c:testAddReplicaWithPolicy_abc s:shard1 r:core_node2 x:testAddReplicaWithPolicy_abc_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 765350 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:34125_solr",
[junit4] 2> "base_url":"http://127.0.0.1:34125/solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testAddReplicaWithPolicy_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 765350 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:34125_solr",
[junit4] 2> "base_url":"http://127.0.0.1:34125/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}
[junit4] 2> 765350 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testAddReplicaWithPolicy_abc//collections/testAddReplicaWithPolicy_abc/state.json/3 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplicaWithPolicy_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:34125_solr",
[junit4] 2> "base_url":"http://127.0.0.1:34125/solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true"}}}}}
[junit4] 2> 765352 INFO (qtp629971562-11151) [n:127.0.0.1:34125_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testAddReplicaWithPolicy_abc.AUTOCREATED&newCollection=true&name=testAddReplicaWithPolicy_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testAddReplicaWithPolicy_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=272
[junit4] 2> 765352 DEBUG (OverseerThreadFactory-7665-thread-1-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.s.c.a.PolicyHelper final release, session 19869270526015834 lived a total of 482ms,
[junit4] 2> 765354 INFO (qtp629971562-11153) [n:127.0.0.1:34125_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> 765451 DEBUG (OverseerStateUpdate-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplicaWithPolicy_abc/state.json version: 3
[junit4] 2> 765451 INFO (zkCallback-7657-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplicaWithPolicy_abc/state.json] for collection [testAddReplicaWithPolicy_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 765451 INFO (zkCallback-7657-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplicaWithPolicy_abc/state.json] for collection [testAddReplicaWithPolicy_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 765451 INFO (zkCallback-7657-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplicaWithPolicy_abc/state.json] for collection [testAddReplicaWithPolicy_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 765452 INFO (qtp629971562-11153) [n:127.0.0.1:34125_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=testAddReplicaWithPolicy_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:34125_solr&wt=javabin&version=2} status=0 QTime=753
[junit4] 2> 765454 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testAddReplicaWithPolicy_xyz
[junit4] 2> 765454 INFO (OverseerCollectionConfigSetProcessor-73359746462253062-127.0.0.1:34125_solr-n_0000000000) [n:127.0.0.1:34125_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 765455 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED
[junit4] 2> 765456 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/protwords.txt
[junit4] 2> 765457 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/managed-schema
[junit4] 2> 765458 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/solrconfig.xml
[junit4] 2> 765460 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 765461 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 765462 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 765464 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 765465 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 765466 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 765467 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 765468 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 765470 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 765471 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 765472 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 765474 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 765475 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 765476 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 765477 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 765478 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 765479 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 765480 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 765482 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 765483 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 765484 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 765485 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 765486 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 765488 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 765489 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 765490 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 765491 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 765492 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 765493 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 765494 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 765496 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 765497 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 765498 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 765499 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 765500 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 765502 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 765503 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 765504 INFO (OverseerThreadFactory-7665-thread-2-processing-n:127.0.0.1:34125_solr) [n:127.0.0.1:34125_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testAddReplicaWithPolicy_xyz.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 765505 INFO (OverseerThreadFactory-7665-t
[...truncated too long message...]
.
[junit4] 2> 2194511 INFO (closeThreadPool-20658-thread-1) [ ] o.a.s.c.Overseer Overseer (id=73359839308283911-127.0.0.1:35585_solr-n_0000000000) closing
[junit4] 2> 2194592 INFO (zkCallback-20595-thread-4) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
[junit4] 2> 2194593 INFO (jetty-closer-20646-thread-1-processing-n:127.0.0.1:35585_solr) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@664388bc{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 2194593 WARN (jetty-launcher-20568-thread-1-SendThread(127.0.0.1:45515)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x104a0629ac00007.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x104a0629ac00007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x104a0629ac00007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 2194594 INFO (jetty-closer-20646-thread-1-processing-n:127.0.0.1:35585_solr) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1f007e95{/solr,null,STOPPED}
[junit4] 2> 2194594 INFO (jetty-closer-20646-thread-1-processing-n:127.0.0.1:35585_solr) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2194694 INFO (jetty-closer-20646-thread-2-processing-n:127.0.0.1:35585_solr) [ ] o.a.s.c.Overseer Overseer (id=73359839308283911-127.0.0.1:35585_solr-n_0000000000) closing
[junit4] 2> 2194695 INFO (jetty-closer-20646-thread-2-processing-n:127.0.0.1:35585_solr) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@6eb648b4{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 2194696 INFO (jetty-closer-20646-thread-2-processing-n:127.0.0.1:35585_solr) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@66857209{/solr,null,STOPPED}
[junit4] 2> 2194696 INFO (jetty-closer-20646-thread-2-processing-n:127.0.0.1:35585_solr) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2194696 INFO (SUITE-SolrCloudReportersTest-seed#[631D9F6D63361824]-worker) [n:127.0.0.1:35585_solr ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 2195003 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> 5 /solr/collections/test_collection/terms/shard2
[junit4] 2> 5 /solr/collections/test_collection/terms/shard1
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 2 /solr/configs/test
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 10 /solr/collections/test_collection/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 34 /solr/collections/test_collection/state.json
[junit4] 2> 13 /solr/overseer/queue
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 6 /solr/collections
[junit4] 2> 4 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 2195005 INFO (SUITE-SolrCloudReportersTest-seed#[631D9F6D63361824]-worker) [n:127.0.0.1:35585_solr ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:45515
[junit4] 2> 2195005 INFO (SUITE-SolrCloudReportersTest-seed#[631D9F6D63361824]-worker) [n:127.0.0.1:35585_solr ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:45515
[junit4] 2> 2195005 INFO (SUITE-SolrCloudReportersTest-seed#[631D9F6D63361824]-worker) [n:127.0.0.1:35585_solr ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 45515
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-BadApples-Tests-8.x/solr/build/solr-core/test/J1/temp/solr.metrics.reporters.solr.SolrCloudReportersTest_631D9F6D63361824-001
[junit4] 2> Apr 19, 2021 9:30:51 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): {}, docValues:{}, maxPointsInLeafNode=655, maxMBSortInHeap=6.472866461249911, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=ar-DZ, timezone=Australia/Queensland
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_281 (64-bit)/cpus=4,threads=1,free=207089752,total=527958016
[junit4] 2> NOTE: All tests run in this JVM: [CustomCollectionTest, PreAnalyzedUpdateProcessorTest, MetricTriggerIntegrationTest, TestSimNodeLostTrigger, SolrCoreMetricManagerTest, SampleTest, CreateRoutedAliasTest, IgnoreLargeDocumentProcessorFactoryTest, TestSolrXml, SearchHandlerTest, TestCollationFieldDocValues, TestSolrJ, TestBulkSchemaConcurrent, TestLegacyField, TestUseDocValuesAsStored, TestCloudNestedDocsSort, DistributedUpdateProcessorTest, TestSubQueryTransformerDistrib, ProtectedTermFilterFactoryTest, TestPivotHelperCode, TestLegacyBM25SimilarityFactory, PluginInfoTest, ClusterStateTest, TestMultiValuedNumericRangeQuery, TestPullReplicaErrorHandling, TestConfigReload, TestSortByMinMaxFunction, TestSchemaNameResource, TestReversedWildcardFilterFactory, TestSimExtremeIndexing, LargeFieldTest, ClassificationUpdateProcessorTest, HdfsThreadLeakTest, TestOverriddenPrefixQueryForCustomFieldType, TestSolrConfigHandler, BJQFilterAccessibleTest, TestDynamicLoading, QueryParsingTest, DistributedFacetPivotSmallTest, TestSimUtils, TestGraphTermsQParserPlugin, TestNestedUpdateProcessor, TestMacros, DirectSolrSpellCheckerTest, TestStreamBody, SignatureUpdateProcessorFactoryTest, CollectionsAPIAsyncDistributedZkTest, ReindexCollectionTest, CustomTermsComponentTest, TestCoreContainer, DistributedTermsComponentTest, MetricsHistoryHandlerTest, AuditLoggerPluginTest, ChaosMonkeyNothingIsSafeTest, TestFacetMethods, SuggestComponentContextFilterQueryTest, XsltUpdateRequestHandlerTest, TestRecoveryHdfs, BasicFunctionalityTest, TestSolrCloudWithKerberosAlt, MultiDestinationAuditLoggerTest, TestFileDictionaryLookup, SuggesterWFSTTest, LoggingHandlerTest, TestRangeQuery, BigEndianAscendingWordSerializerTest, ShardRoutingTest, DeleteReplicaTest, SolrCLIZkUtilsTest, TestJoin, TestDistributedSearch, EmbeddedSolrNoSerializeTest, WrapperMergePolicyFactoryTest, SignificantTermsQParserPluginTest, HdfsBackupRepositoryIntegrationTest, TestChildDocTransformerHierarchy, CdcrUpdateLogTest, DistributedQueryComponentOptimizationTest, TestDocSet, TaggingAttributeTest, DistributedFacetExistsSmallTest, TestMergePolicyConfig, TimeRoutedAliasUpdateProcessorTest, DistributedIntervalFacetingTest, TestSystemCollAutoCreate, TestRestoreCore, MetricsHistoryWithAuthIntegrationTest, TriggerEventQueueTest, NestedShardedAtomicUpdateTest, PolyFieldTest, AutoScalingHandlerTest, WordBreakSolrSpellCheckerTest, TestCoreAdminApis, LeaderTragicEventTest, HttpPartitionOnCommitTest, FieldAnalysisRequestHandlerTest, HttpTriggerListenerTest, TestCSVResponseWriter, ManagedSchemaRoundRobinCloudTest, ChangedSchemaMergeTest, UniqFieldsUpdateProcessorFactoryTest, TestBM25SimilarityFactory, VMParamsZkACLAndCredentialsProvidersTest, TestDFRSimilarityFactory, TestFieldSortValues, DateRangeFieldTest, TestConfigOverlay, TestCrossCoreJoin, TestComplexPhraseLeadingWildcard, ResourceLoaderTest, PhrasesIdentificationComponentTest, TestPhraseSuggestions, TestCircuitBreaker, TestZkChroot, CleanupOldIndexTest, DistribCursorPagingTest, DistribJoinFromCollectionTest, DistributedQueueTest, DistributedVersionInfoTest, DocValuesNotIndexedTest, ForceLeaderTest, ForceLeaderWithTlogReplicasTest, LeaderElectionContextKeyTest, LeaderElectionIntegrationTest, MultiSolrCloudTestCaseTest, OverseerCollectionConfigSetProcessorTest, OverseerModifyCollectionTest, OverseerTest, ReplicationFactorTest, TestCloudConsistency, TestDistribDocBasedVersion, TestLeaderElectionWithEmptyReplica, TestRandomRequestDistribution, TestSSLRandomization, TestSizeLimitedDistributedMap, TestSkipOverseerOperations, TestTolerantUpdateProcessorCloud, TestUtilizeNode, ZkFailoverTest, AssignTest, AsyncCallRequestStatusResponseTest, BackupRestoreApiErrorConditionsTest, CollectionReloadTest, LocalFSCloudIncrementalBackupTest, TestCollectionAPI, TestLocalFSCloudBackupRestore, AutoAddReplicasIntegrationTest, ScheduledTriggerTest, SearchRateTriggerIntegrationTest, SearchRateTriggerTest, CdcrOpsAndBoundariesTest, CdcrReplicationHandlerTest, CdcrRequestHandlerTest, CdcrVersionReplicationTest, HdfsRecoverLeaseTest, ZkDynamicConfigTest, AlternateDirectoryTest, CachingDirectoryFactoryTest, TestInitParams, TestQuerySenderNoQuery, TestDistribPackageStore, TestReplicationHandlerDiskOverFlow, TestSolrConfigHandlerCloud, ShowFileRequestHandlerTest, StatsReloadRaceTest, TestCollectionAPIs, ZookeeperStatusHandlerTest, TestExpandComponent, HighlighterConfigTest, HighlighterMaxOffsetTest, HighlighterWithoutStoredIdTest, TestUnifiedSolrHighlighter, TestUnifiedSolrHighlighterWithoutStoredId, TestLogWatcher, MetricsConfigTest, SolrMetricsIntegrationTest, SolrJmxReporterCloudTest, SolrJmxReporterTest, SolrCloudReportersTest]
[junit4] Completed [807/936 (2!)] on J1 in 197.48s, 2 tests, 1 error <<< FAILURES!
[...truncated 51235 lines...]