You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2020/09/22 20:15:18 UTC
[JENKINS-Experimental-GC] Lucene-Solr-master-Linux
(64bit/jdk-12.0.2) - Build # 28112 - Failure!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-master-Linux/28112/
Java: 64bit/jdk-12.0.2 -XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC
All tests passed
Build Log:
[...truncated 1293 lines...]
# A fatal error has been detected by the Java Runtime Environment:
#
# SIGILL (0x4) at pc=0x00007fbba6508822, pid=956454, tid=990482
#
# JRE version: OpenJDK Runtime Environment (12.0.2+10) (build 12.0.2+10)
# Java VM: OpenJDK 64-Bit Server VM (12.0.2+10, mixed mode, tiered, z gc, linux-amd64)
# Problematic frame:
# J 37514 c2 org.apache.solr.schema.TrieField.readableToIndexed(Ljava/lang/CharSequence;Lorg/apache/lucene/util/BytesRefBuilder;)V (147 bytes) @ 0x00007fbba6508822 [0x00007fbba6508060+0x00000000000007c2]
#
# No core dump will be written. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
#
# An error report file with more information is saved as:
# /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-cwd/hs_err_pid956454.log
Compiled method (c2) 316396 37514 4 org.apache.solr.schema.TrieField::readableToIndexed (147 bytes)
total in heap [0x00007fbba6507d90,0x00007fbba6509638] = 6312
relocation [0x00007fbba6507f08,0x00007fbba6508048] = 320
main code [0x00007fbba6508060,0x00007fbba6508b20] = 2752
stub code [0x00007fbba6508b20,0x00007fbba6508c08] = 232
oops [0x00007fbba6508c08,0x00007fbba6508c20] = 24
metadata [0x00007fbba6508c20,0x00007fbba6508ca8] = 136
scopes data [0x00007fbba6508ca8,0x00007fbba65090b0] = 1032
scopes pcs [0x00007fbba65090b0,0x00007fbba6509430] = 896
dependencies [0x00007fbba6509430,0x00007fbba6509440] = 16
handler table [0x00007fbba6509440,0x00007fbba6509590] = 336
nul chk table [0x00007fbba6509590,0x00007fbba6509638] = 168
Compiled method (c1) 316396 37295 3 org.apache.solr.schema.TrieField::toInternal (6 bytes)
total in heap [0x00007fbb9ef76710,0x00007fbb9ef77328] = 3096
relocation [0x00007fbb9ef76888,0x00007fbb9ef76938] = 176
main code [0x00007fbb9ef76940,0x00007fbb9ef76fe0] = 1696
stub code [0x00007fbb9ef76fe0,0x00007fbb9ef770b8] = 216
oops [0x00007fbb9ef770b8,0x00007fbb9ef770c8] = 16
metadata [0x00007fbb9ef770c8,0x00007fbb9ef77110] = 72
scopes data [0x00007fbb9ef77110,0x00007fbb9ef771f0] = 224
scopes pcs [0x00007fbb9ef771f0,0x00007fbb9ef77310] = 288
dependencies [0x00007fbb9ef77310,0x00007fbb9ef77318] = 8
nul chk table [0x00007fbb9ef77318,0x00007fbb9ef77328] = 16
Could not load hsdis-amd64.so; library not loadable; PrintAssembly is disabled
#
# If you would like to submit a bug report, please visit:
# https://github.com/AdoptOpenJDK/openjdk-build/issues
#
WARNING: Test org.apache.solr.cloud.CloudExitableDirectoryReaderTest wrote 10,756,699 bytes of output.
org.apache.solr.cloud.DeleteShardTest > test FAILED
org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:39265/solr: The slice: shard2 is currently active. Only non-active (or custom-hashed) slices can be deleted.
at __randomizedtesting.SeedInfo.seed([C25224E35213E9E:84711D949BDD5366]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:698)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:269)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251)
at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:390)
at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:360)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1170)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:931)
at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:865)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:229)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:246)
at org.apache.solr.cloud.DeleteShardTest.test(DeleteShardTest.java:85)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:567)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
at java.base/java.lang.Thread.run(Thread.java:835)
org.apache.solr.cloud.DeleteShardTest > test suite's output saved to /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/test-results/test/outputs/OUTPUT-org.apache.solr.cloud.DeleteShardTest.txt, copied below:
2> 553263 INFO (SUITE-DeleteShardTest-seed#[C25224E35213E9E]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/server/solr/configsets/_default/conf'
2> 553264 INFO (SUITE-DeleteShardTest-seed#[C25224E35213E9E]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-tmp/solr.cloud.DeleteShardTest_C25224E35213E9E-001/data-dir-81-001
2> 553265 WARN (SUITE-DeleteShardTest-seed#[C25224E35213E9E]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=30 numCloses=30
2> 553265 INFO (SUITE-DeleteShardTest-seed#[C25224E35213E9E]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
2> 553265 INFO (SUITE-DeleteShardTest-seed#[C25224E35213E9E]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
2> 553267 INFO (SUITE-DeleteShardTest-seed#[C25224E35213E9E]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
2> 553270 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
2> 553271 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-tmp/solr.cloud.DeleteShardTest_C25224E35213E9E-001/tempDir-001
2> 553271 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
2> 553274 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
2> 553275 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
2> 553276 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
2> 553374 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer start zk server on port: 34571
2> 553375 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:34571
2> 553375 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34571
2> 553375 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34571
2> 553376 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 553378 INFO (zkConnectionManagerCallback-6880-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 553378 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 553384 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 553388 INFO (zkConnectionManagerCallback-6882-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 553388 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 553390 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 553393 INFO (zkConnectionManagerCallback-6884-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 553393 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 553408 WARN (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]-SendThread(127.0.0.1:34571)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10000e2f5c80002.
2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10000e2f5c80002, likely server has closed socket
2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10000e2f5c80002, likely server has closed socket
2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
2> 553512 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
2> 553512 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
2> 553512 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
2> 553512 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
2> 553512 INFO (jetty-launcher-6885-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
2> 553512 INFO (jetty-launcher-6885-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
2> 553518 INFO (jetty-launcher-6885-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
2> 553518 INFO (jetty-launcher-6885-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
2> 553518 INFO (jetty-launcher-6885-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
2> 553518 INFO (jetty-launcher-6885-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
2> 553518 INFO (jetty-launcher-6885-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
2> 553518 INFO (jetty-launcher-6885-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
2> 553520 INFO (jetty-launcher-6885-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1b732ff2{/solr,null,AVAILABLE}
2> 553520 INFO (jetty-launcher-6885-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@bf8b6b9{/solr,null,AVAILABLE}
2> 553524 INFO (jetty-launcher-6885-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5055e74e{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:39265}
2> 553524 INFO (jetty-launcher-6885-thread-1) [ ] o.e.j.s.Server Started @553698ms
2> 553524 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=39265}
2> 553525 ERROR (jetty-launcher-6885-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
2> 553525 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
2> 553525 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
2> 553525 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
2> 553525 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
2> 553525 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-09-22T19:58:51.340456Z
2> 553526 INFO (jetty-launcher-6885-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@19dff9ff{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:40111}
2> 553526 INFO (jetty-launcher-6885-thread-2) [ ] o.e.j.s.Server Started @553700ms
2> 553526 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=40111}
2> 553527 ERROR (jetty-launcher-6885-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
2> 553527 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
2> 553527 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
2> 553527 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
2> 553527 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
2> 553527 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-09-22T19:58:51.342175Z
2> 553531 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 553531 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 553538 INFO (zkConnectionManagerCallback-6887-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 553538 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 553543 INFO (zkConnectionManagerCallback-6889-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 553543 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 553550 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
2> 553550 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
2> 553659 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
2> 553660 WARN (jetty-launcher-6885-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@59d40369[provider=null,keyStore=null,trustStore=null]
2> 553660 WARN (jetty-launcher-6885-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@59d40369[provider=null,keyStore=null,trustStore=null]
2> 553663 WARN (jetty-launcher-6885-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@df2dc2f[provider=null,keyStore=null,trustStore=null]
2> 553663 WARN (jetty-launcher-6885-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@df2dc2f[provider=null,keyStore=null,trustStore=null]
2> 553664 INFO (jetty-launcher-6885-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34571/solr
2> 553665 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 553666 INFO (zkConnectionManagerCallback-6899-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 553666 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 553702 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:40111_solr
2> 553702 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.c.Overseer Overseer (id=72058568824061957-127.0.0.1:40111_solr-n_0000000000) starting
2> 553705 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:40111_solr
2> 553705 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40111_solr
2> 553706 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
2> 553706 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
2> 553707 WARN (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_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
2> 553714 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
2> 553725 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40111.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5504f688
2> 553729 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40111.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5504f688
2> 553729 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40111.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5504f688
2> 553730 INFO (jetty-launcher-6885-thread-2) [n:127.0.0.1:40111_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-tmp/solr.cloud.DeleteShardTest_C25224E35213E9E-001/tempDir-001/node2
2> 553759 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
2> 553760 WARN (jetty-launcher-6885-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3e08fd06[provider=null,keyStore=null,trustStore=null]
2> 553760 WARN (jetty-launcher-6885-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3e08fd06[provider=null,keyStore=null,trustStore=null]
2> 553762 WARN (jetty-launcher-6885-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@436d2719[provider=null,keyStore=null,trustStore=null]
2> 553762 WARN (jetty-launcher-6885-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@436d2719[provider=null,keyStore=null,trustStore=null]
2> 553762 INFO (jetty-launcher-6885-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:34571/solr
2> 553763 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 553764 INFO (zkConnectionManagerCallback-6919-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 553764 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 553765 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
2> 553766 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:39265_solr as DOWN
2> 553767 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
2> 553767 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:39265_solr
2> 553767 INFO (zkCallback-6898-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
2> 553767 INFO (zkCallback-6918-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
2> 553767 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
2> 553768 WARN (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_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
2> 553774 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
2> 553783 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39265.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5504f688
2> 553788 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39265.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5504f688
2> 553788 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39265.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5504f688
2> 553789 INFO (jetty-launcher-6885-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-tmp/solr.cloud.DeleteShardTest_C25224E35213E9E-001/tempDir-001/node1
2> 553792 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
2> 553796 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 553797 INFO (zkConnectionManagerCallback-6932-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 553797 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 553798 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
2> 553798 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:34571/solr ready
2> 553821 INFO (qtp2139153338-11274) [n:127.0.0.1:39265_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params collection.configName=conf&name=deleteShard&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2 and sendToOCPQueue=true
2> 553828 INFO (OverseerThreadFactory-6904-thread-1-processing-n:127.0.0.1:40111_solr) [n:127.0.0.1:40111_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection deleteShard
2> 553934 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.o.SliceMutator createReplica() {
2> "operation":"ADDREPLICA",
2> "collection":"deleteShard",
2> "shard":"shard1",
2> "core":"deleteShard_shard1_replica_n1",
2> "state":"down",
2> "base_url":"https://127.0.0.1:40111/solr",
2> "node_name":"127.0.0.1:40111_solr",
2> "type":"NRT",
2> "waitForFinalState":"false"}
2> 553935 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.o.SliceMutator createReplica() {
2> "operation":"ADDREPLICA",
2> "collection":"deleteShard",
2> "shard":"shard2",
2> "core":"deleteShard_shard2_replica_n2",
2> "state":"down",
2> "base_url":"https://127.0.0.1:39265/solr",
2> "node_name":"127.0.0.1:39265_solr",
2> "type":"NRT",
2> "waitForFinalState":"false"}
2> 554153 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr x:deleteShard_shard2_replica_n2 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=deleteShard_shard2_replica_n2&action=CREATE&numShards=2&collection=deleteShard&shard=shard2&wt=javabin&version=2&replicaType=NRT
2> 554153 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr x:deleteShard_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=deleteShard_shard1_replica_n1&action=CREATE&numShards=2&collection=deleteShard&shard=shard1&wt=javabin&version=2&replicaType=NRT
2> 554153 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr x:deleteShard_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 1000 transient cores
2> 554161 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
2> 554161 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 9.0.0
2> 554170 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.s.IndexSchema Schema name=minimal
2> 554170 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=minimal
2> 554172 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
2> 554172 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
2> 554172 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'deleteShard_shard1_replica_n1' using configuration from configset conf, trusted=true
2> 554172 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.CoreContainer Creating SolrCore 'deleteShard_shard2_replica_n2' using configuration from configset conf, trusted=true
2> 554173 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_40111.solr.core.deleteShard.shard1.replica_n1' (registry 'solr.core.deleteShard.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5504f688
2> 554173 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_39265.solr.core.deleteShard.shard2.replica_n2' (registry 'solr.core.deleteShard.shard2.replica_n2') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5504f688
2> 554173 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.SolrCore [[deleteShard_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-tmp/solr.cloud.DeleteShardTest_C25224E35213E9E-001/tempDir-001/node2/deleteShard_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-tmp/solr.cloud.DeleteShardTest_C25224E35213E9E-001/tempDir-001/node2/deleteShard_shard1_replica_n1/data/]
2> 554173 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.SolrCore [[deleteShard_shard2_replica_n2] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-tmp/solr.cloud.DeleteShardTest_C25224E35213E9E-001/tempDir-001/node1/deleteShard_shard2_replica_n2], dataDir=[/home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-tmp/solr.cloud.DeleteShardTest_C25224E35213E9E-001/tempDir-001/node1/deleteShard_shard2_replica_n2/data/]
2> 554197 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
2> 554197 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
2> 554198 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
2> 554198 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
2> 554198 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
2> 554198 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
2> 554199 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
2> 554199 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
2> 554200 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
2> 554200 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
2> 554200 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
2> 554200 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
2> 554200 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1678565422677360640
2> 554200 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
2> 554201 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
2> 554202 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1678565422679457792
2> 554204 INFO (searcherExecutor-6935-thread-1-processing-n:127.0.0.1:40111_solr x:deleteShard_shard1_replica_n1 c:deleteShard s:shard1 r:core_node3) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.SolrCore [deleteShard_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
2> 554204 INFO (searcherExecutor-6936-thread-1-processing-n:127.0.0.1:39265_solr x:deleteShard_shard2_replica_n2 c:deleteShard s:shard2 r:core_node4) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.SolrCore [deleteShard_shard2_replica_n2] Registered new searcher autowarm time: 0 ms
2> 554210 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/deleteShard/terms/shard1 to Terms{values={core_node3=0}, version=0}
2> 554210 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/deleteShard/leaders/shard1
2> 554210 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/deleteShard/terms/shard2 to Terms{values={core_node4=0}, version=0}
2> 554210 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/deleteShard/leaders/shard2
2> 554213 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
2> 554213 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
2> 554213 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40111/solr/deleteShard_shard1_replica_n1/
2> 554214 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
2> 554214 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:40111/solr/deleteShard_shard1_replica_n1/ has no replicas
2> 554214 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/deleteShard/leaders/shard1/leader after winning as /collections/deleteShard/leader_elect/shard1/election/72058568824061957-core_node3-n_0000000000
2> 554215 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
2> 554215 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:40111/solr/deleteShard_shard1_replica_n1/ shard1
2> 554215 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
2> 554215 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:39265/solr/deleteShard_shard2_replica_n2/
2> 554215 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
2> 554215 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.SyncStrategy https://127.0.0.1:39265/solr/deleteShard_shard2_replica_n2/ has no replicas
2> 554215 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/deleteShard/leaders/shard2/leader after winning as /collections/deleteShard/leader_elect/shard2/election/72058568824061958-core_node4-n_0000000000
2> 554216 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:39265/solr/deleteShard_shard2_replica_n2/ shard2
2> 554317 INFO (zkCallback-6918-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554317 INFO (zkCallback-6898-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554317 INFO (zkCallback-6918-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554317 INFO (zkCallback-6898-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554317 INFO (zkCallback-6918-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554318 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard s:shard2 r:core_node4 x:deleteShard_shard2_replica_n2 ] o.a.s.c.ZkController I am the leader, no recovery necessary
2> 554318 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
2> 554320 ERROR (qtp906687546-11282) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 r:core_node3 x:deleteShard_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader Collection deleteShard is not lazy nor watched!
2> 554321 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node4&collection.configName=conf&newCollection=true&name=deleteShard_shard2_replica_n2&action=CREATE&numShards=2&collection=deleteShard&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=168
2> 554321 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node3&collection.configName=conf&newCollection=true&name=deleteShard_shard1_replica_n1&action=CREATE&numShards=2&collection=deleteShard&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=168
2> 554322 INFO (qtp2139153338-11274) [n:127.0.0.1:39265_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
2> 554421 INFO (zkCallback-6918-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554421 INFO (zkCallback-6918-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554421 INFO (zkCallback-6898-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554421 INFO (zkCallback-6898-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554421 INFO (zkCallback-6918-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554421 INFO (zkCallback-6898-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554421 INFO (zkCallback-6918-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554422 INFO (qtp2139153338-11274) [n:127.0.0.1:39265_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=conf&name=deleteShard&nrtReplicas=1&action=CREATE&numShards=2&wt=javabin&version=2} status=0 QTime=600
2> 554422 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: deleteShard
2> 554426 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deleteshard with params action=DELETESHARD&collection=deleteShard&shard=shard1&wt=javabin&version=2 and sendToOCPQueue=true
2> 554428 INFO (OverseerThreadFactory-6904-thread-2-processing-n:127.0.0.1:40111_solr) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 ] o.a.s.c.a.c.DeleteShardCmd Delete shard invoked
2> 554429 ERROR (OverseerThreadFactory-6904-thread-2-processing-n:127.0.0.1:40111_solr) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: deleteShard operation: deleteshard failed:org.apache.solr.common.SolrException: The slice: shard1 is currently active. Only non-active (or custom-hashed) slices can be deleted.
2> at org.apache.solr.cloud.api.collections.DeleteShardCmd.call(DeleteShardCmd.java:88)
2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:257)
2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:524)
2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2> at java.base/java.lang.Thread.run(Thread.java:835)
2>
2> 554429 INFO (OverseerCollectionConfigSetProcessor-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
2> 554432 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=DELETESHARD&collection=deleteShard&shard=shard1&wt=javabin&version=2} status=400 QTime=6
2> 554432 INFO (OverseerCollectionConfigSetProcessor-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
2> 554433 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.o.SliceMutator Update shard state invoked for collection: deleteShard with message: {
2> "shard1":"inactive",
2> "collection":"deleteShard",
2> "operation":"updateshardstate"}
2> 554433 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.o.SliceMutator Update shard state shard1 to inactive
2> 554434 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.SolrCloudTestCase waitForState (deleteShard): Expected shard shard1 to be in state inactive
2> 554535 INFO (zkCallback-6918-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554535 INFO (zkCallback-6931-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554535 INFO (zkCallback-6918-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554535 INFO (zkCallback-6898-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554535 INFO (zkCallback-6918-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554535 INFO (zkCallback-6931-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554535 INFO (zkCallback-6918-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554535 INFO (zkCallback-6898-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554535 INFO (zkCallback-6898-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554537 INFO (qtp2139153338-11274) [n:127.0.0.1:39265_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deleteshard with params action=DELETESHARD&collection=deleteShard&shard=shard1&wt=javabin&version=2 and sendToOCPQueue=true
2> 554538 INFO (OverseerThreadFactory-6904-thread-3-processing-n:127.0.0.1:40111_solr) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 ] o.a.s.c.a.c.DeleteShardCmd Delete shard invoked
2> 554538 INFO (OverseerThreadFactory-6904-thread-3-processing-n:127.0.0.1:40111_solr) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 ] o.a.s.c.a.c.DeleteShardCmd Deleting replica for collection=deleteShard shard=shard1 on node=127.0.0.1:40111_solr
2> 554540 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr x:deleteShard_shard1_replica_n1 ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.deleteShard.shard1.replica_n1 tag=null
2> 554540 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr x:deleteShard_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@524a4ecc: rootName = solr_40111, domain = solr.core.deleteShard.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.deleteShard.shard1.replica_n1/com.codahale.metrics.MetricRegistry@5a85eed0
2> 554550 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr ] o.a.s.c.SolrCore [deleteShard_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@629ad60e
2> 554550 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.deleteShard.shard1.replica_n1 tag=SolrCore@629ad60e
2> 554550 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.deleteShard.shard1.leader tag=SolrCore@629ad60e
2> 554550 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
2> 554552 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/deleteShard/terms/shard1 to Terms{values={}, version=1}
2> 554552 INFO (qtp906687546-11282) [n:127.0.0.1:40111_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={deleteInstanceDir=true&core=deleteShard_shard1_replica_n1&deleteMetricsHistory=true&qt=/admin/cores&deleteDataDir=true&action=UNLOAD&wt=javabin&version=2&deleteIndex=true} status=0 QTime=13
2> 554653 INFO (zkCallback-6918-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554653 INFO (zkCallback-6918-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554653 INFO (zkCallback-6898-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554653 INFO (zkCallback-6918-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554653 INFO (zkCallback-6918-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554653 INFO (zkCallback-6898-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554653 INFO (zkCallback-6898-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554655 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.o.CollectionMutator Removing collection: deleteShard shard: shard1 from clusterstate
2> 554755 INFO (zkCallback-6898-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554755 INFO (zkCallback-6898-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554755 INFO (zkCallback-6898-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554755 INFO (zkCallback-6918-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554755 INFO (zkCallback-6918-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554755 INFO (zkCallback-6898-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554755 INFO (zkCallback-6918-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554755 INFO (zkCallback-6918-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554756 INFO (OverseerThreadFactory-6904-thread-3-processing-n:127.0.0.1:40111_solr) [n:127.0.0.1:40111_solr c:deleteShard s:shard1 ] o.a.s.c.a.c.DeleteShardCmd Successfully deleted collection: deleteShard , shard: shard1
2> 554756 INFO (qtp2139153338-11274) [n:127.0.0.1:39265_solr c:deleteShard ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=DELETESHARD&collection=deleteShard&shard=shard1&wt=javabin&version=2} status=0 QTime=219
2> 554757 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.SolrCloudTestCase waitForState (deleteShard): Expected 'shard1' to be removed
2> 554758 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.SolrCloudTestCase waitForState (deleteShard): Expected shard shard2 to be in state construction
2> 554758 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.o.SliceMutator Update shard state invoked for collection: deleteShard with message: {
2> "shard2":"construction",
2> "collection":"deleteShard",
2> "operation":"updateshardstate"}
2> 554758 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.o.SliceMutator Update shard state shard2 to construction
2> 554859 INFO (zkCallback-6918-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554859 INFO (zkCallback-6918-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554859 INFO (zkCallback-6918-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554859 INFO (zkCallback-6931-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554859 INFO (zkCallback-6918-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554859 INFO (zkCallback-6931-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/deleteShard/state.json] for collection [deleteShard] has occurred - updating... (live nodes size: [2])
2> 554860 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :deleteshard with params action=DELETESHARD&collection=deleteShard&shard=shard2&wt=javabin&version=2 and sendToOCPQueue=true
2> 554860 INFO (OverseerThreadFactory-6904-thread-4-processing-n:127.0.0.1:40111_solr) [n:127.0.0.1:40111_solr c:deleteShard s:shard2 ] o.a.s.c.a.c.DeleteShardCmd Delete shard invoked
2> 554861 ERROR (OverseerThreadFactory-6904-thread-4-processing-n:127.0.0.1:40111_solr) [n:127.0.0.1:40111_solr c:deleteShard s:shard2 ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: deleteShard operation: deleteshard failed:org.apache.solr.common.SolrException: The slice: shard2 is currently active. Only non-active (or custom-hashed) slices can be deleted.
2> at org.apache.solr.cloud.api.collections.DeleteShardCmd.call(DeleteShardCmd.java:88)
2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:257)
2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:524)
2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
2> at java.base/java.lang.Thread.run(Thread.java:835)
2>
2> 554861 INFO (OverseerCollectionConfigSetProcessor-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
2> 554861 INFO (OverseerCollectionConfigSetProcessor-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper
2> 554861 INFO (qtp2139153338-11276) [n:127.0.0.1:39265_solr c:deleteShard ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={action=DELETESHARD&collection=deleteShard&shard=shard2&wt=javabin&version=2} status=400 QTime=1
2> 554970 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1949660575
2> 554970 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:40111_solr
2> 554971 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.c.ZkController Publish this node as DOWN...
2> 554971 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:40111_solr as DOWN
2> 554971 INFO (zkCallback-6898-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
2> 554971 INFO (zkCallback-6918-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (1)
2> 554972 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=2044235222
2> 554972 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:39265_solr
2> 554972 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.c.ZkController Publish this node as DOWN...
2> 554972 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.c.ZkController Publish node=127.0.0.1:39265_solr as DOWN
2> 554972 INFO (zkCallback-6918-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
2> 554972 INFO (zkCallback-6898-thread-3) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
2> 554980 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
2> 554980 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@32d046e4: rootName = solr_40111, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@3761c24c
2> 554980 INFO (coreCloseExecutor-6953-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.c.SolrCore [deleteShard_shard2_replica_n2] CLOSING SolrCore org.apache.solr.core.SolrCore@35a4dea5
2> 554980 INFO (coreCloseExecutor-6953-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.deleteShard.shard2.replica_n2 tag=SolrCore@35a4dea5
2> 554980 INFO (coreCloseExecutor-6953-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3d62d01e: rootName = solr_39265, domain = solr.core.deleteShard.shard2.replica_n2, service url = null, agent id = null] for registry solr.core.deleteShard.shard2.replica_n2/com.codahale.metrics.MetricRegistry@2fa6f0e3
2> 554984 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
2> 554984 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@aebb31d: rootName = solr_40111, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@22a82f7d
2> 554985 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
2> 554985 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5b241536: rootName = solr_40111, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@561fc79d
2> 554985 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
2> 554988 INFO (closeThreadPool-6954-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72058568824061957-127.0.0.1:40111_solr-n_0000000000) closing
2> 554988 INFO (coreCloseExecutor-6953-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.deleteShard.shard2.leader tag=SolrCore@35a4dea5
2> 554988 INFO (OverseerStateUpdate-72058568824061957-127.0.0.1:40111_solr-n_0000000000) [n:127.0.0.1:40111_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:40111_solr
2> 554988 INFO (coreCloseExecutor-6953-thread-1) [n:127.0.0.1:39265_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
2> 554992 INFO (closeThreadPool-6954-thread-2) [ ] o.a.s.c.Overseer Overseer (id=72058568824061957-127.0.0.1:40111_solr-n_0000000000) closing
2> 554996 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
2> 554996 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@14aa5550: rootName = solr_39265, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@1035d15a
2> 555000 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
2> 555000 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@25ffafc6: rootName = solr_39265, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@22a82f7d
2> 555001 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
2> 555001 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@78199acd: rootName = solr_39265, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@561fc79d
2> 555001 INFO (jetty-closer-6945-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
2> 555108 INFO (jetty-closer-6945-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72058568824061957-127.0.0.1:40111_solr-n_0000000000) closing
2> 555112 INFO (jetty-closer-6945-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@19dff9ff{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
2> 555112 INFO (jetty-closer-6945-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@1b732ff2{/solr,null,UNAVAILABLE}
2> 555112 INFO (jetty-closer-6945-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
2> 555209 INFO (jetty-closer-6945-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5055e74e{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:0}
2> 555209 INFO (jetty-closer-6945-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@bf8b6b9{/solr,null,UNAVAILABLE}
2> 555209 INFO (jetty-closer-6945-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
2> 555209 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
2> 555413 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
2> Maximum concurrent create/delete watches above limit:
2>
2> 3 /solr/collections/deleteShard/terms/shard1
2> 3 /solr/aliases.json
2> 2 /solr/packages.json
2> 2 /solr/security.json
2> 2 /solr/configs/conf
2> 2 /solr/collections/deleteShard/terms/shard2
2>
2> Maximum concurrent data watches above limit:
2>
2> 55 /solr/collections/deleteShard/state.json
2> 3 /solr/clusterprops.json
2>
2> Maximum concurrent children watches above limit:
2>
2> 23 /solr/overseer/queue
2> 14 /solr/overseer/collection-queue-work
2> 10 /solr/live_nodes
2> 6 /solr/collections
2>
2> 555415 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:34571
2> 555415 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:34571
2> 555415 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 34571
2> 555417 INFO (TEST-DeleteShardTest.test-seed#[C25224E35213E9E]) [ ] o.a.s.SolrTestCaseJ4 ###Ending test
> org.apache.solr.client.solrj.impl.BaseHttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:39265/solr: The slice: shard2 is currently active. Only non-active (or custom-hashed) slices can be deleted.
> at __randomizedtesting.SeedInfo.seed([C25224E35213E9E:84711D949BDD5366]:0)
> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:698)
> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:269)
> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:251)
> at org.apache.solr.client.solrj.impl.LBSolrClient.doRequest(LBSolrClient.java:390)
> at org.apache.solr.client.solrj.impl.LBSolrClient.request(LBSolrClient.java:360)
> at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.sendRequest(BaseCloudSolrClient.java:1170)
> at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.requestWithRetryOnStaleState(BaseCloudSolrClient.java:931)
> at org.apache.solr.client.solrj.impl.BaseCloudSolrClient.request(BaseCloudSolrClient.java:865)
> at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:229)
> at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:246)
> at org.apache.solr.cloud.DeleteShardTest.test(DeleteShardTest.java:85)
> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.base/java.lang.reflect.Method.invoke(Method.java:567)
> at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1754)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:942)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:978)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:992)
> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
> at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:819)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:470)
> at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:951)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:836)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:887)
> at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:898)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
> at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
> at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
> at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
> at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
> at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
> at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:370)
> at com.carrotsearch.randomizedtesting.ThreadLeakControl.lambda$forkTimeoutingTask$0(ThreadLeakControl.java:826)
> at java.base/java.lang.Thread.run(Thread.java:835)
2> NOTE: reproduce with: gradlew test --tests DeleteShardTest.test -Dtests.seed=C25224E35213E9E -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr-SC -Dtests.timezone=VST -Dtests.asserts=true -Dtests.file.encoding=UTF-8
2> 555424 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testDirectoryCleanupAfterDeleteShard
2> 555425 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/tmp/tests-tmp/solr.cloud.DeleteShardTest_C25224E35213E9E-001/tempDir-002
2> 555425 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
2> 555428 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
2> 555428 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
2> 555429 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
2> 555528 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer start zk server on port: 33523
2> 555528 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:33523
2> 555528 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:33523
2> 555528 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 33523
2> 555530 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 555536 INFO (zkConnectionManagerCallback-6958-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 555536 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 555545 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 555554 INFO (zkConnectionManagerCallback-6960-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 555554 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 555556 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 555572 INFO (zkConnectionManagerCallback-6962-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 555572 INFO (TEST-DeleteShardTest.testDirectoryCleanupAfterDeleteShard-seed#[C25224E35213E9E]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 555687 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
2> 555687 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
2> 555687 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
2> 555687 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
2> 555688 INFO (jetty-launcher-6963-thread-1) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
2> 555688 INFO (jetty-launcher-6963-thread-2) [ ] o.e.j.s.Server jetty-9.4.27.v20200227; built: 2020-02-27T18:37:21.340Z; git: a304fd9f351f337e7c0e2a7c28878dd536149c6c; jvm 12.0.2+10
2> 555715 INFO (jetty-launcher-6963-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
2> 555715 INFO (jetty-launcher-6963-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
2> 555715 INFO (jetty-launcher-6963-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
2> 555715 INFO (jetty-launcher-6963-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
2> 555715 INFO (jetty-launcher-6963-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
2> 555715 INFO (jetty-launcher-6963-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
2> 555715 INFO (jetty-launcher-6963-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@119b3e55{/solr,null,AVAILABLE}
2> 555715 INFO (jetty-launcher-6963-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6d282aa6{/solr,null,AVAILABLE}
2> 555717 INFO (jetty-launcher-6963-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@e59918a{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:36367}
2> 555717 INFO (jetty-launcher-6963-thread-2) [ ] o.e.j.s.Server Started @555891ms
2> 555717 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=36367}
2> 555717 ERROR (jetty-launcher-6963-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
2> 555717 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
2> 555717 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
2> 555717 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
2> 555717 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
2> 555717 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-09-22T19:58:53.532803Z
2> 555718 INFO (jetty-launcher-6963-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2aa76354{ssl, (ssl, alpn, http/1.1, h2)}{127.0.0.1:34071}
2> 555718 INFO (jetty-launcher-6963-thread-1) [ ] o.e.j.s.Server Started @555892ms
2> 555718 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=34071}
2> 555718 ERROR (jetty-launcher-6963-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
2> 555718 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
2> 555718 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 9.0.0
2> 555718 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
2> 555718 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr
2> 555718 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2020-09-22T19:58:53.533735Z
2> 555719 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 555719 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
2> 555724 INFO (zkConnectionManagerCallback-6965-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 555724 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 555724 INFO (zkConnectionManagerCallback-6967-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
2> 555724 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
2> 555724 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
2> 555724 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
2> 555733 WARN (jetty-launcher-6963-thread-1-SendThread(127.0.0.1:33523)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x10000e2fe3a0004.
2> => EndOfStreamException: Unable to read additional data from server sessionid 0x10000e2fe3a0004, likely server has closed socket
2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x10000e2fe3a0004, likely server has closed socket
2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
2> 555835 INFO (jetty-launcher-6963-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
2> 555836 INFO (jetty-launcher-6963-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
2> 555836 WARN (jetty-launcher-6963-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5ea515d0[provider=null,keyStore=null,trustStore=null]
2> 555836 WARN (jetty-launcher-6963-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5ea515d0[provider=null,keyStore=null,trustStore=null]
2> 555840 WARN (jetty-launcher-6963-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Clien
[...truncated too long message...]
> Task :lucene:analysis:stempel:cleanTaskTmp
> Task :lucene:analysis:smartcn:cleanTaskTmp
> Task :lucene:analysis:phonetic:cleanTaskTmp
> Task :lucene:analysis:opennlp:cleanTaskTmp
> Task :lucene:analysis:nori:cleanTaskTmp
> Task :lucene:analysis:morfologik:cleanTaskTmp
> Task :lucene:analysis:kuromoji:cleanTaskTmp
> Task :lucene:analysis:icu:cleanTaskTmp
> Task :lucene:analysis:common:cleanTaskTmp
> Task :solr:webapp:cleanTaskTmp
> Task :solr:test-framework:cleanTaskTmp
> Task :solr:solrj:cleanTaskTmp
> Task :solr:solr-ref-guide:cleanTaskTmp
> Task :solr:server:cleanTaskTmp
> Task :solr:core:cleanTaskTmp
> Task :lucene:test-framework:cleanTaskTmp
> Task :lucene:suggest:cleanTaskTmp
> Task :lucene:spatial3d:cleanTaskTmp
> Task :lucene:spatial-extras:cleanTaskTmp
> Task :lucene:sandbox:cleanTaskTmp
> Task :lucene:replicator:cleanTaskTmp
> Task :lucene:queryparser:cleanTaskTmp
> Task :lucene:queries:cleanTaskTmp
> Task :lucene:monitor:cleanTaskTmp
> Task :lucene:misc:cleanTaskTmp
> Task :lucene:memory:cleanTaskTmp
> Task :lucene:luke:cleanTaskTmp
> Task :lucene:join:cleanTaskTmp
> Task :lucene:highlighter:cleanTaskTmp
> Task :lucene:grouping:cleanTaskTmp
> Task :lucene:facet:cleanTaskTmp
> Task :lucene:expressions:cleanTaskTmp
> Task :lucene:demo:cleanTaskTmp
> Task :lucene:core:cleanTaskTmp
> Task :lucene:codecs:cleanTaskTmp
> Task :lucene:classification:cleanTaskTmp
> Task :lucene:benchmark:cleanTaskTmp
> Task :cleanGradleTmp
> Task :lucene:backward-codecs:check
> Task :lucene:benchmark:check
> Task :lucene:classification:check
> Task :lucene:codecs:check
> Task :lucene:core:check
> Task :lucene:demo:check
> Task :lucene:expressions:check
> Task :lucene:facet:check
> Task :lucene:grouping:check
> Task :lucene:highlighter:check
> Task :lucene:join:check
> Task :lucene:luke:check
> Task :lucene:memory:check
> Task :lucene:misc:check
> Task :lucene:monitor:check
> Task :lucene:queries:check
> Task :lucene:queryparser:check
> Task :lucene:replicator:check
> Task :lucene:sandbox:check
> Task :lucene:spatial-extras:check
> Task :lucene:spatial3d:check
> Task :lucene:suggest:check
> Task :lucene:test-framework:check
> Task :solr:contrib:check
> Task :solr:docker:check
> Task :solr:example:check
> Task :solr:packaging:check
> Task :solr:server:check
> Task :solr:solr-ref-guide:setupLazyProps
Building ref guide with:
javadocLink -> https://docs.oracle.com/en/java/javase/11/docs/api/
solrGuideDraftStatus -> DRAFT
solrRootPath -> /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/
solrDocsVersion -> 9.0
solrGuideVersionPath -> 9_0
htmlSolrJavadocs -> https://lucene.apache.org/solr/9_0_0/
htmlLuceneJavadocs -> https://lucene.apache.org/core/9_0_0/
buildDate -> 2020-09-22
buildYear -> 2020
ivyCommonsCodec -> 1.13
ivyDropwizardMetrics -> 4.1.5
ivyLog4j -> 2.13.2
ivyOpennlpTools -> 1.9.1
ivyTika -> 1.24
ivyZookeeper -> 3.6.2
> Task :solr:solr-ref-guide:prepareSources
> Task :solr:solr-ref-guide:buildNavDataFiles
Building up tree of all known pages
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by org.jruby.util.SecurityHelper (file:/home/jenkins/.gradle/caches/modules-2/files-2.1/org.jruby/jruby-core/9.2.6.0/653601d48b38528e76840c6eac1ae46e2dae1a74/jruby-core-9.2.6.0.jar) to field java.lang.reflect.Field.modifiers
WARNING: Please consider reporting this to the maintainers of org.jruby.util.SecurityHelper
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
Looping over pages to build nav data
Creating /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solr-ref-guide/build/content/_data/scrollnav.json
Creating /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solr-ref-guide/build/content/_data/sidebar.json
> Task :solr:solr-ref-guide:jrubyPrepare
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.headius.backport9.modules.Modules (file:/home/jenkins/.gradle/caches/modules-2/files-2.1/org.jruby/jruby-complete/9.2.9.0/8e44ce7a1417966d89957bd766a148601e28828b/jruby-complete-9.2.9.0.jar) to method sun.nio.ch.NativeThread.signal(long)
WARNING: Please consider reporting this to the maintainers of com.headius.backport9.modules.Modules
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
Successfully installed ffi-1.13.1-java
Successfully installed rb-inotify-0.10.1
Successfully installed rb-fsevent-0.10.4
Successfully installed sass-listen-4.0.0
Successfully installed forwardable-extended-2.6.0
Successfully installed listen-3.2.1
Ruby Sass has reached end-of-life and should no longer be used.
* If you use Sass as a command-line tool, we recommend using Dart Sass, the new
primary implementation: https://sass-lang.com/install
* If you use Sass as a plug-in for a Ruby web framework, we recommend using the
sassc gem: https://github.com/sass/sassc-ruby#readme
* For more details, please refer to the Sass blog:
https://sass-lang.com/blog/posts/7828841
Successfully installed sass-3.7.4
Successfully installed public_suffix-4.0.6
Successfully installed temple-0.8.2
Successfully installed asciidoctor-2.0.10
Successfully installed safe_yaml-1.0.5
Successfully installed rouge-1.11.1
Successfully installed pathutil-0.16.2
Successfully installed mercenary-0.3.6
Successfully installed liquid-4.0.3
Successfully installed kramdown-1.17.0
Successfully installed jekyll-watch-1.5.1
Successfully installed jekyll-sass-converter-1.5.2
Successfully installed colorator-1.1.0
Successfully installed addressable-2.7.0
Successfully installed concurrent-ruby-1.0.5-java
Successfully installed tilt-2.0.10
Successfully installed slim-4.0.1
Successfully installed jekyll-3.5.2
Successfully installed jekyll-asciidoc-3.0.0
25 gems installed
> Task :solr:solr-ref-guide:buildSiteJekyll
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.headius.backport9.modules.Modules (file:/home/jenkins/.gradle/caches/modules-2/files-2.1/org.jruby/jruby-complete/9.2.9.0/8e44ce7a1417966d89957bd766a148601e28828b/jruby-complete-9.2.9.0.jar) to method sun.nio.ch.NativeThread.signal(long)
WARNING: Please consider reporting this to the maintainers of com.headius.backport9.modules.Modules
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release
Configuration file: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solr-ref-guide/build/content/_config.yml
[33m Deprecation: The 'gems' configuration option has been renamed to 'plugins'. Please update your config file accordingly.[0m
Source: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/solr-ref-guide/build/content
Destination: ../html-site
Incremental build: disabled. Enable with --incremental
Generating...
done in 17.651 seconds.
Auto-regeneration: disabled. Use --watch to enable.
> Task :solr:solr-ref-guide:buildSite
Processed 2433 links (1750 relative) to 3530 anchors in 245 files
> Task :solr:solr-ref-guide:check
> Task :solr:solrj:check
> Task :solr:test-framework:check
> Task :solr:webapp:check
> Task :lucene:analysis:common:check
> Task :lucene:analysis:icu:check
> Task :lucene:analysis:kuromoji:check
> Task :lucene:analysis:morfologik:check
> Task :lucene:analysis:nori:check
> Task :lucene:analysis:opennlp:check
> Task :lucene:analysis:phonetic:check
> Task :lucene:analysis:smartcn:check
> Task :lucene:analysis:stempel:check
> Task :solr:contrib:analysis-extras:check
> Task :solr:contrib:analytics:check
> Task :solr:contrib:clustering:check
> Task :solr:contrib:extraction:check
> Task :solr:contrib:jaegertracer-configurator:check
> Task :solr:contrib:langid:check
> Task :solr:contrib:ltr:check
> Task :solr:contrib:prometheus-exporter:check
> Task :solr:docker:package:check
ERROR: The following test(s) have failed:
- org.apache.solr.cloud.DeleteShardTest.test (:solr:core)
Test output: /home/jenkins/workspace/Lucene-Solr-master-Linux/solr/core/build/test-results/test/outputs/OUTPUT-org.apache.solr.cloud.DeleteShardTest.txt
Reproduce with: gradlew :solr:core:test --tests "org.apache.solr.cloud.DeleteShardTest" -Ptests.jvms=6 -Ptests.haltonfailure=false -Ptests.jvmargs=-XX:-UseCompressedOops -XX:+UnlockExperimentalVMOptions -XX:+UseZGC -Ptests.seed=C25224E35213E9E -Ptests.multiplier=3 -Ptests.badapples=false -Ptests.file.encoding=UTF-8
FAILURE: Build failed with an exception.
* What went wrong:
Execution failed for task ':solr:core:test'.
> Process 'Gradle Test Executor 138' finished with non-zero exit value 134
This problem might be caused by incorrect test process configuration.
Please refer to the test execution section in the User Manual at https://docs.gradle.org/6.4.1/userguide/java_testing.html#sec:test_execution
* Try:
Run with --stacktrace option to get the stack trace. Run with --info or --debug option to get more log output. Run with --scan to get full insights.
* Get more help at https://help.gradle.org
Deprecated Gradle features were used in this build, making it incompatible with Gradle 7.0.
Use '--warning-mode all' to show the individual deprecation warnings.
See https://docs.gradle.org/6.4.1/userguide/command_line_interface.html#sec:command_line_warnings
BUILD FAILED in 44m 11s
837 actionable tasks: 837 executed
Build step 'Invoke Gradle script' changed build result to FAILURE
Build step 'Invoke Gradle script' marked build as failure
Archiving artifacts
[Java] Skipping execution of recorder since overall result is 'FAILURE'
Recording test results
[Checks API] No suitable checks publisher found.
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any